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/01/23 02:24:36 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-11.0.6) - Build #
5561 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5561/
Java: 64bit/jdk-11.0.6 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestCryptoKeys.test
Error Message:
{ "responseHeader":{ "status":500, "QTime":30081}, "errorMessages":["3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]\n"], "WARNING":"This response format is experimental. It is likely to change in the future.", "error":{ "metadata":[ "error-class","org.apache.solr.common.SolrException", "root-error-class","org.apache.solr.common.SolrException"], "msg":"3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]", "trace":"org.apache.solr.common.SolrException: 3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2582)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:799)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:578)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)\n\tat org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n", "code":500}} expected null, but was:<[3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/] ]>
Stack Trace:
java.lang.AssertionError: {
"responseHeader":{
"status":500,
"QTime":30081},
"errorMessages":["3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]\n"],
"WARNING":"This response format is experimental. It is likely to change in the future.",
"error":{
"metadata":[
"error-class","org.apache.solr.common.SolrException",
"root-error-class","org.apache.solr.common.SolrException"],
"msg":"3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]",
"trace":"org.apache.solr.common.SolrException: 3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:813)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2582)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:799)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:578)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)\n\tat org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n",
"code":500}}
expected null, but was:<[3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]
]>
at __randomizedtesting.SeedInfo.seed([89FC24F20B138AE:80CBFD958E4D5556]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotNull(Assert.java:755)
at org.junit.Assert.assertNull(Assert.java:737)
at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:179)
at org.apache.solr.cloud.TestCryptoKeys.test(TestCryptoKeys.java:140)
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:566)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:834)
Build Log:
[...truncated 14921 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCryptoKeys
[junit4] 2> 1423085 INFO (SUITE-TestCryptoKeys-seed#[89FC24F20B138AE]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/data-dir-95-001
[junit4] 2> 1423085 INFO (SUITE-TestCryptoKeys-seed#[89FC24F20B138AE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1423086 INFO (SUITE-TestCryptoKeys-seed#[89FC24F20B138AE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1423087 INFO (SUITE-TestCryptoKeys-seed#[89FC24F20B138AE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1423087 INFO (SUITE-TestCryptoKeys-seed#[89FC24F20B138AE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ib/wr
[junit4] 2> 1423090 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1423090 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1423091 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1423194 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer start zk server on port:61770
[junit4] 2> 1423194 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61770
[junit4] 2> 1423194 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61770
[junit4] 2> 1423194 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61770
[junit4] 2> 1423197 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1423202 INFO (zkConnectionManagerCallback-5975-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1423202 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423208 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1423223 INFO (zkConnectionManagerCallback-5977-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1423224 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423259 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1423266 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1423285 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1423291 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1423294 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1423316 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1423343 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1423348 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1423357 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1423365 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1423377 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1423428 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1423434 INFO (zkConnectionManagerCallback-5981-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1423434 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1423561 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1424955 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1424955 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1424955 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1424956 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 1424963 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1424963 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1424963 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1424965 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22cc9b72{/ib/wr,null,AVAILABLE}
[junit4] 2> 1424969 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6789685{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61783}
[junit4] 2> 1424969 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.s.Server Started @1425027ms
[junit4] 2> 1424969 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ib/wr, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/tempDir-001/control/data, hostPort=61783, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/control-001/cores, replicaType=NRT}
[junit4] 2> 1424970 ERROR (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1424970 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1424971 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1424972 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1424972 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1424972 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T01:37:53.917509Z
[junit4] 2> 1424980 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1424992 INFO (zkConnectionManagerCallback-5983-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1424992 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1425098 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1425098 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/control-001/solr.xml
[junit4] 2> 1425105 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1425106 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1425111 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1425422 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1425423 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7175b4b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1425423 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7175b4b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1425428 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42cd14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1425428 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42cd14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1425429 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61770/solr
[junit4] 2> 1425432 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1425435 INFO (zkConnectionManagerCallback-5990-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1425435 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1425547 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1425566 INFO (zkConnectionManagerCallback-5992-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1425567 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1427173 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61783_ib%2Fwr
[junit4] 2> 1427180 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.Overseer Overseer (id=72058050910027781-127.0.0.1:61783_ib%2Fwr-n_0000000000) starting
[junit4] 2> 1427197 INFO (OverseerStateUpdate-72058050910027781-127.0.0.1:61783_ib%2Fwr-n_0000000000) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61783_ib%2Fwr
[junit4] 2> 1427198 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61783_ib%2Fwr
[junit4] 2> 1427205 INFO (OverseerStateUpdate-72058050910027781-127.0.0.1:61783_ib%2Fwr-n_0000000000) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1427262 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1427262 WARN (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1427321 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1427347 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1427358 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1427359 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1427360 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/control-001/cores
[junit4] 2> 1427397 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1427400 INFO (zkConnectionManagerCallback-6001-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1427400 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1427403 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1427423 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61770/solr ready
[junit4] 2> 1427485 INFO (qtp1764038943-18271) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:61783_ib%252Fwr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1427534 INFO (OverseerThreadFactory-4585-thread-1-processing-n:127.0.0.1:61783_ib%2Fwr) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1427755 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1427756 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1429160 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1429273 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 1429527 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1429848 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1429849 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1429849 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1429851 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=428, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1429855 WARN (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1429986 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1429986 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1429989 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1429989 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1429992 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=26, maxMergedSegmentMB=49.267578125, floorSegmentMB=1.9912109375, forceMergeDeletesPctAllowed=18.609601942974447, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12458065597985737, deletesPctAllowed=45.02758984338706
[junit4] 2> 1429993 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@102485ae[control_collection_shard1_replica_n1] main]
[junit4] 2> 1430004 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1430006 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1430007 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1430007 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656481098185572352
[junit4] 2> 1430011 INFO (searcherExecutor-4590-thread-1-processing-n:127.0.0.1:61783_ib%2Fwr x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@102485ae[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1430029 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1430029 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 1430098 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1430098 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1430099 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61783/ib/wr/control_collection_shard1_replica_n1/
[junit4] 2> 1430102 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1430104 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:61783/ib/wr/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1430104 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72058050910027781-core_node2-n_0000000000
[junit4] 2> 1430108 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61783/ib/wr/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1430114 INFO (zkCallback-5991-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1430115 INFO (zkCallback-5991-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1430119 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1430123 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2368
[junit4] 2> 1430128 INFO (qtp1764038943-18271) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1430251 INFO (zkCallback-5991-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1430251 INFO (zkCallback-5991-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1430251 INFO (zkCallback-5991-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1430263 INFO (qtp1764038943-18271) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:61783_ib%252Fwr&wt=javabin&version=2} status=0 QTime=2778
[junit4] 2> 1430266 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1430478 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1430483 INFO (zkConnectionManagerCallback-6007-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1430483 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1430487 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1430493 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61770/solr ready
[junit4] 2> 1430494 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1430515 INFO (qtp1764038943-18269) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1430539 INFO (OverseerThreadFactory-4585-thread-2-processing-n:127.0.0.1:61783_ib%2Fwr) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1430540 INFO (OverseerCollectionConfigSetProcessor-72058050910027781-127.0.0.1:61783_ib%2Fwr-n_0000000000) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1430764 WARN (OverseerThreadFactory-4585-thread-2-processing-n:127.0.0.1:61783_ib%2Fwr) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1430771 INFO (qtp1764038943-18269) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1430775 INFO (qtp1764038943-18269) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=260
[junit4] 2> 1430778 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected:1
[junit4] 2> 1430778 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1430779 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected:1
[junit4] 2> 1430779 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1430781 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected:1
[junit4] 2> 1430781 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1430781 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1432253 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-1-001 of type NRT
[junit4] 2> 1432304 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1432304 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1432304 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1432304 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 1432306 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1432306 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1432307 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1432307 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f77f142{/ib/wr,null,AVAILABLE}
[junit4] 2> 1432309 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1941e684{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61864}
[junit4] 2> 1432309 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server Started @1432367ms
[junit4] 2> 1432309 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ib/wr, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/tempDir-001/jetty1, hostPort=61864, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-1-001/cores}
[junit4] 2> 1432309 ERROR (closeThreadPool-6008-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1432309 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1432310 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1432310 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1432310 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1432310 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T01:38:01.255121Z
[junit4] 2> 1432312 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1432319 INFO (zkConnectionManagerCallback-6010-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1432319 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1432433 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1432436 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-1-001/solr.xml
[junit4] 2> 1432450 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1432450 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1432452 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1432547 INFO (OverseerCollectionConfigSetProcessor-72058050910027781-127.0.0.1:61783_ib%2Fwr-n_0000000000) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1432695 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1432741 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4431ba59[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1432741 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4431ba59[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1432832 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@39a388c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1432835 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@39a388c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1432839 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61770/solr
[junit4] 2> 1432841 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1432845 INFO (zkConnectionManagerCallback-6017-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1432846 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1432999 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1433010 INFO (zkConnectionManagerCallback-6019-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1433012 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1433095 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1433142 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.ZkController Publish node=127.0.0.1:61864_ib%2Fwr as DOWN
[junit4] 2> 1433166 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1433166 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61864_ib%2Fwr
[junit4] 2> 1433174 INFO (zkCallback-5991-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1433175 INFO (zkCallback-6018-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1433175 INFO (zkCallback-6006-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1433195 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1433196 WARN (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1433230 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1433382 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1433446 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1433446 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1433448 INFO (closeThreadPool-6008-thread-1) [n:127.0.0.1:61864_ib%2Fwr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-1-001/cores
[junit4] 2> 1433523 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61864_ib%2Fwr
[junit4] 2> 1434338 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-2-001 of type NRT
[junit4] 2> 1434344 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1434345 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1434345 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1434345 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 1434349 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1434349 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1434349 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1434350 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f5044d7{/ib/wr,null,AVAILABLE}
[junit4] 2> 1434354 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@235ec842{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61871}
[junit4] 2> 1434354 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server Started @1434412ms
[junit4] 2> 1434354 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ib/wr, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/tempDir-001/jetty2, hostPort=61871, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-2-001/cores}
[junit4] 2> 1434354 ERROR (closeThreadPool-6008-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1434354 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1434354 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1434355 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1434355 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1434355 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T01:38:03.300078Z
[junit4] 2> 1434356 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1434360 INFO (zkConnectionManagerCallback-6025-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1434360 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1434471 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1434471 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-2-001/solr.xml
[junit4] 2> 1434474 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1434474 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1434475 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1434736 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1434738 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@a696980[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1434738 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@a696980[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1434753 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d7b677[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1434753 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d7b677[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1434772 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61770/solr
[junit4] 2> 1434777 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1434780 INFO (zkConnectionManagerCallback-6032-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1434781 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1434901 INFO (zkConnectionManagerCallback-6034-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1434901 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1434901 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1434994 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1435056 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:61871_ib%2Fwr as DOWN
[junit4] 2> 1435076 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1435076 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61871_ib%2Fwr
[junit4] 2> 1435084 INFO (zkCallback-6006-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1435084 INFO (zkCallback-6018-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1435084 INFO (zkCallback-5991-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1435087 INFO (zkCallback-6033-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1435116 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1435117 WARN (closeThreadPool-6008-thread-1) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1435191 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1435225 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1435256 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1435256 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1435258 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-2-001/cores
[junit4] 2> 1435471 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61871_ib%2Fwr
[junit4] 2> 1435631 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-3-001 of type NRT
[junit4] 2> 1435636 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1435636 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1435636 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1435636 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 1435654 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1435654 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1435654 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1435655 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3792eafc{/ib/wr,null,AVAILABLE}
[junit4] 2> 1435663 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7843e2e3{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61893}
[junit4] 2> 1435664 INFO (closeThreadPool-6008-thread-1) [ ] o.e.j.s.Server Started @1435721ms
[junit4] 2> 1435664 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ib/wr, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/tempDir-001/jetty3, hostPort=61893, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-3-001/cores, replicaType=NRT}
[junit4] 2> 1435665 ERROR (closeThreadPool-6008-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1435666 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1435666 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1435666 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1435666 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1435666 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T01:38:04.611793Z
[junit4] 2> 1435671 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1435685 INFO (zkConnectionManagerCallback-6040-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1435691 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1435808 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1435810 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-3-001/solr.xml
[junit4] 2> 1435813 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1435813 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1435814 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1436050 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1436057 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3c3446f9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1436057 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3c3446f9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1436086 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@787fa753[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1436087 WARN (closeThreadPool-6008-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@787fa753[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1436088 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61770/solr
[junit4] 2> 1436092 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1436095 INFO (zkConnectionManagerCallback-6047-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1436100 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1436328 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1436330 INFO (zkConnectionManagerCallback-6049-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1436331 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1436747 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1436871 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:61893_ib%2Fwr as DOWN
[junit4] 2> 1436911 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1436913 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61893_ib%2Fwr
[junit4] 2> 1436968 INFO (zkCallback-6006-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1436969 INFO (zkCallback-6033-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1436972 INFO (zkCallback-6018-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1436988 INFO (zkCallback-5991-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1436989 INFO (zkCallback-6048-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1437027 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1437027 WARN (closeThreadPool-6008-thread-1) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1437104 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1437179 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1437209 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1437212 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1437221 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-3-001/cores
[junit4] 2> 1437224 INFO (TEST-TestCryptoKeys.test-seed#[89FC24F20B138AE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-4-001 of type NRT
[junit4] 2> 1437254 WARN (closeThreadPool-6008-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1437254 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1437254 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1437254 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
[junit4] 2> 1437287 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1437287 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1437287 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1437287 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@337a9770{/ib/wr,null,AVAILABLE}
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@53dfa078{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61899}
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.e.j.s.Server Started @1437347ms
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ib/wr, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/tempDir-001/jetty4, hostPort=61899, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-4-001/cores}
[junit4] 2> 1437290 ERROR (closeThreadPool-6008-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1437290 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T01:38:06.235913Z
[junit4] 2> 1437292 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1437313 INFO (zkConnectionManagerCallback-6055-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1437313 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1437390 INFO (closeThreadPool-6008-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61893_ib%2Fwr
[junit4] 2> 1437423 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1437424 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-4-001/solr.xml
[junit4] 2> 1437429 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1437429 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1437430 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1437682 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1437689 WARN (closeThreadPool-6008-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2db1fb4f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1437689 WARN (closeThreadPool-6008-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2db1fb4f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1437701 WARN (closeThreadPool-6008-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6523c93e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1437701 WARN (closeThreadPool-6008-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6523c93e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1437705 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61770/solr
[junit4] 2> 1437718 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1437719 INFO (zkConnectionManagerCallback-6062-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1437719 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1437832 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1437835 INFO (zkConnectionManagerCallback-6064-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1437836 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1437893 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1437949 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.ZkController Publish node=127.0.0.1:61899_ib%2Fwr as DOWN
[junit4] 2> 1437956 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1437956 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61899_ib%2Fwr
[junit4] 2> 1437976 INFO (zkCallback-5991-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437977 INFO (zkCallback-6006-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437977 INFO (zkCallback-6033-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437977 INFO (zkCallback-6018-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437977 INFO (zkCallback-6048-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437978 INFO (zkCallback-6063-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1437994 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1437995 WARN (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1438023 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1438050 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1438066 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1438068 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7901ee37
[junit4] 2> 1438071 INFO (closeThreadPool-6008-thread-2) [n:127.0.0.1:61899_ib%2Fwr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001/shard-4-001/cores
[junit4] 2> 1438186 INFO (closeThreadPool-6008-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:61899_ib%2Fwr
[junit4] 2> 1438206 INFO (qtp1764038943-18268) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:61864_ib%252Fwr&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1438217 INFO (qtp1764038943-18271) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:61893_ib%252Fwr&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1438219 INFO (qtp1764038943-18269) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:61871_ib%252Fwr&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1438245 INFO (qtp1764038943-18443) [n:127.0.0.1:61783_ib%2Fwr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:61899_ib%252Fwr&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQue
[...truncated too long message...]
$Command.handleCommands(SolrConfigHandler.java:522)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:363)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:139)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:208)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2582)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:799)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:578)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:419)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:351)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1607)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1297)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1577)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1212)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:500)\n\tat org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383)\n\tat org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:270)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:543)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:398)\n\tat org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:161)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)\n\tat org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)\n\tat java.base/java.lang.Thread.run(Thread.java:834)\n",
[junit4] > "code":500}}
[junit4] > expected null, but was:<[3 out of 5 the property overlay to be of version 2 within 30 seconds! Failed cores: [https://127.0.0.1:61864/ib/wr/collection1_shard1_replica_n1/, https://127.0.0.1:61871/ib/wr/collection1_shard1_replica_n5/, https://127.0.0.1:61899/ib/wr/collection1_shard1_replica_n7/]
[junit4] > ]>
[junit4] > at __randomizedtesting.SeedInfo.seed([89FC24F20B138AE:80CBFD958E4D5556]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:179)
[junit4] > at org.apache.solr.cloud.TestCryptoKeys.test(TestCryptoKeys.java:140)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.TestCryptoKeys_89FC24F20B138AE-001
[junit4] 2> Jan 23, 2020 1:39:16 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {blobName=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=FST50, type=Lucene84, md5=Lucene84}, docValues:{size=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Asserting), version=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1127, maxMBSortInHeap=7.698052322084738, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7c4f176d), locale=zh-Hant-MO, timezone=Asia/Yerevan
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=90347448,total=296394752
[junit4] 2> NOTE: All tests run in this JVM: [TestCustomDocTransformer, TestCloudRecovery, BlobRepositoryCloudTest, TolerantUpdateProcessorTest, TestSolrCloudWithHadoopAuthPlugin, TestAtomicUpdateErrorCases, TestExactStatsCache, MultiThreadedOCPTest, FuzzySearchTest, StatsReloadRaceTest, NoCacheHeaderTest, TestManagedSchemaThreadSafety, TestRebalanceLeaders, TestCloudNestedDocsSort, SparseHLLTest, SSLMigrationTest, ZkStateWriterTest, TestFieldSortValues, TestDynamicLoading, DistributedFacetPivotLongTailTest, TestExclusionRuleCollectionAccess, TestLMJelinekMercerSimilarityFactory, TestSchemaVersionResource, ConcurrentCreateRoutedAliasTest, SyncSliceTest, TestCloudDeleteByQuery, AutoAddReplicasIntegrationTest, BasicDistributedZk2Test, TestSolrCachePerf, TestChildDocTransformer, MoreLikeThisHandlerTest, BasicFunctionalityTest, HdfsRestartWhileUpdatingTest, IndexSchemaTest, TestSimGenericDistributedQueue, SolrCoreCheckLockOnStartupTest, SolrMetricsIntegrationTest, ConnectionReuseTest, DistributedQueryComponentCustomSortTest, SimpleFacetsTest, FastVectorHighlighterTest, TestManagedSynonymGraphFilterFactory, CopyFieldTest, HdfsChaosMonkeyNothingIsSafeTest, AutoscalingHistoryHandlerTest, TestSizeLimitedDistributedMap, TestDistributedMap, RandomizedTaggerTest, ReplaceNodeTest, RecoveryAfterSoftCommitTest, SpellingQueryConverterTest, TransactionLogTest, CdcrBidirectionalTest, ClusterStateTest, BasicAuthOnSingleNodeTest, DataDrivenBlockJoinTest, ChaosMonkeySafeLeaderTest, CdcrVersionReplicationTest, TimeZoneUtilsTest, TestCoreAdminApis, DeleteInactiveReplicaTest, TestSubQueryTransformerDistrib, TestConfig, TestStressCloudBlindAtomicUpdates, JavaBinAtomicUpdateMultivalueTest, TestCSVLoader, DeleteStatusTest, SpellCheckComponentTest, TestNumericTerms64, ResolveAnalyzerByNameTest, LeaderElectionIntegrationTest, EchoParamsTest, RankQueryTest, CoreMergeIndexesAdminHandlerTest, TriggerCooldownIntegrationTest, TestCoreContainer, PolyFieldTest, TestSolrConfigHandler, TestRemoteStreaming, LeaderVoteWaitTimeoutTest, AtomicUpdateProcessorFactoryTest, V2ApiIntegrationTest, TestPullReplica, TestJsonFacetsStatsParsing, OverseerCollectionConfigSetProcessorTest, BJQFilterAccessibleTest, TestValueSourceCache, TestMinHashQParser, TestRandomFaceting, TestTrie, ProtectedTermFilterFactoryTest, TestDeprecatedFilters, TestLuceneMatchVersion, TestWordDelimiterFilterFactory, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, DistributedQueueTest, DistributedVersionInfoTest, HealthCheckHandlerTest, HttpPartitionWithTlogReplicasTest, OverseerTest, TestConfigSetsAPIZkFailure, TestCryptoKeys]
[junit4] Completed [508/900 (1!)] on J1 in 84.89s, 1 test, 1 failure <<< FAILURES!
[...truncated 53814 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5561/consoleText
[repro] Revision: ffba54a827778c2e9288f45ce5801b2546786bb6
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestCryptoKeys -Dtests.method=test -Dtests.seed=89FC24F20B138AE -Dtests.slow=true -Dtests.locale=zh-Hant-MO -Dtests.timezone=Asia/Yerevan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestCryptoKeys
[repro] ant compile-test
[...truncated 3407 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestCryptoKeys" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=89FC24F20B138AE -Dtests.slow=true -Dtests.locale=zh-Hant-MO -Dtests.timezone=Asia/Yerevan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.TestCryptoKeys
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk-12.0.2) - Build #
5562 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5562/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.search.TestRecovery.testExistOldBufferLog
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestRecovery
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
Build Log:
[...truncated 16340 lines...]
[junit4] Suite: org.apache.solr.search.TestRecovery
[junit4] 2> 1937802 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1937805 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001
[junit4] 2> 1937805 WARN (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=108 numCloses=108
[junit4] 2> 1937805 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1937806 INFO (SUITE-TestRecovery-seed#[6042CA86275A0531]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1937816 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testVersionsOnRestart
[junit4] 2> 1937816 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
[junit4] 2> 1937817 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1937818 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1937882 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1937914 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1938231 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1938615 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1044) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestRecovery.beforeTest(TestRecovery.java:80) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1938615 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1938631 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4892c32c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938632 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4892c32c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938668 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e4a7b23[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938668 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e4a7b23[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1938670 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1938890 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1938891 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1938987 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1938998 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1938998 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1939000 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1939059 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1939120 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1939352 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1939388 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1939388 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1939389 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001/]
[junit4] 2> 1939393 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1939564 WARN (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1939755 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1939755 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1939781 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1939781 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1939800 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=273, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7754277102425448]
[junit4] 2> 1939805 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@77d9488a[collection1] main]
[junit4] 2> 1939805 WARN (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1939806 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1939806 INFO (coreLoadExecutor-9028-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656503499535417344
[junit4] 2> 1939810 INFO (searcherExecutor-9029-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77d9488a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1939817 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1939820 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@179e99d3[collection1] realtime]
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 5
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503499554291712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1939824 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4bb945fa commitCommandVersion:1656503499554291712
[junit4] 2> 1940034 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2269614c[collection1] main]
[junit4] 2> 1940050 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1940059 INFO (searcherExecutor-9029-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2269614c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1940060 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 235
[junit4] 2> 1940199 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D1 (1656503499803852800)]} 0 136
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D2 (1656503499947507712)]} 0 0
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503499948556288,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1940200 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4bb945fa commitCommandVersion:1656503499948556288
[junit4] 2> 1941897 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@573b9393[collection1] main]
[junit4] 2> 1941898 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1941900 INFO (searcherExecutor-9029-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@573b9393[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1941901 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 1700
[junit4] 2> 1941902 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D1&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1941903 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D2&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1941903 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1335650550
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@25d6e843
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@25d6e843
[junit4] 2> 1941904 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6e39ef34: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6736f5c4
[junit4] 2> 1941920 INFO (coreCloseExecutor-9034-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1942112 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1942112 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@238c5f9d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1c075525
[junit4] 2> 1942131 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1942131 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1deeaa68: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1942135 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1942135 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f733c5e: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1942172 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1942224 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1942269 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1942481 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1942774 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1044) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:814) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestRecovery.testVersionsOnRestart(TestRecovery.java:1106) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1942786 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1942947 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@54dbfb56[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942948 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@54dbfb56[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942965 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@19bab902[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942965 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@19bab902[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1942968 WARN (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1943178 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1943179 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1943325 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943346 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943346 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943349 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1943395 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1943454 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1943634 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1943784 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1943785 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1943785 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-205-001/]
[junit4] 2> 1943799 WARN (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1944300 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 1944300 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1944303 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1944304 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1944312 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1944492 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d05fe72[collection1] main]
[junit4] 2> 1944493 WARN (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1944493 INFO (coreLoadExecutor-9044-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1944505 INFO (searcherExecutor-9045-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d05fe72[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, java.vendor=AdoptOpenJDK, os=Mac OS X, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1944616 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[D1 (1656503504501473280)]} 0 73
[junit4] 2> 1944617 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503504580116480,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1944617 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@794ff119 commitCommandVersion:1656503504580116480
[junit4] 2> 1946713 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2da98cca[collection1] main]
[junit4] 2> 1946718 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1946724 INFO (searcherExecutor-9045-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2da98cca[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2/1:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, java.vendor=AdoptOpenJDK, os=Mac OS X, timestamp=1579764843049, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]:delGen=1) Uninverting(_1(9.0.0):C1:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764847435, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1946738 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2121
[junit4] 2> 1946739 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:D1&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=3
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1946742 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=850936715
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dc84f2c
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@4dc84f2c
[junit4] 2> 1946851 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55e88bb9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6923d3b6
[junit4] 2> 1946907 INFO (coreCloseExecutor-9050-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 1947164 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1947164 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@16e03f70: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@234da325
[junit4] 2> 1947174 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1947174 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2700f18: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1947177 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1947177 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1782daec: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1947192 INFO (TEST-TestRecovery.testVersionsOnRestart-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testVersionsOnRestart
[junit4] 2> 1947235 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDropBuffered
[junit4] 2> 1947235 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 1947236 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1947236 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1947285 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1947338 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001
[junit4] 2> 1947345 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1947460 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1947701 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1044) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestRecovery.beforeTest(TestRecovery.java:80) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:835) [?:?]
[junit4] 2> 1947701 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1947707 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7af167f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947707 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7af167f5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947732 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3fd8331e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947732 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3fd8331e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1947742 WARN (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1947855 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1947861 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1947935 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947952 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947952 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1947960 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1947997 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1948020 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1948191 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@75202365
[junit4] 2> 1948210 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/]
[junit4] 2> 1948225 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=345, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3951854026626319]
[junit4] 2> 1948409 WARN (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1948626 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1948626 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1948629 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1948629 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1948643 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=273, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7754277102425448]
[junit4] 2> 1948644 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7faa9055[collection1] main]
[junit4] 2> 1948645 WARN (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1948645 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1948645 INFO (coreLoadExecutor-9060-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656503508803780608
[junit4] 2> 1948647 INFO (searcherExecutor-9061-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7faa9055[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1948649 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1948650 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1948651 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55de34d9[collection1] realtime]
[junit4] 2> 1948651 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:* (-9223372036854775807)} 0 1
[junit4] 2> 1948652 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503508811120640,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1948652 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:1656503508811120640
[junit4] 2> 1948874 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@cf80d7b[collection1] main]
[junit4] 2> 1948874 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1948876 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@cf80d7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1948877 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 225
[junit4] 2> 1948883 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948884 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C1 (2)]} 0 8
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (3)]} 0 0
[junit4] 2> 1948893 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C3 (4)]} 0 0
[junit4] 2> 1948894 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=null}
[junit4] 2> 1948897 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1948898 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C4 (5)]} 0 0
[junit4] 2> 1948898 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C5 (6)]} 0 0
[junit4] 2> 1948903 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/buffer.tlog.0000028350895351907 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 1949169 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1949169 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1950318 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@47c5911a[collection1] main]
[junit4] 2> 1950323 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1950324 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@47c5911a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1950331 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C4 (5), C5 (6)]} 0 1427
[junit4] 2> 1950331 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 1950333 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 1950335 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C100 (7)]} 0 2
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C101 (8)]} 0 0
[junit4] 2> 1950337 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C103 (9)]} 0 4
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C104 (10)]} 0 0
[junit4] 2> 1950342 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950344 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/tlog.0000000000000000002 refcount=1}}
[junit4] 2> 1950344 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C105 (11)]} 0 0
[junit4] 2> 1950345 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C106 (12)]} 0 0
[junit4] 2> 1950347 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Starting log replay tlog{file=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-206-001/tlog/buffer.tlog.0000028352342164294 refcount=2} active=true starting pos=0 inSortedOrder=false
[junit4] 2> 1950348 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 start commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1950351 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1951803 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bf07d98[collection1] main]
[junit4] 2> 1951805 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1951811 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2bf07d98[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):C4:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764853102, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1951811 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] {add=[C105 (11), C106 (12)]} 0 1464
[junit4] 2> 1951811 INFO (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 1951816 WARN (recoveryExecutor-9063-thread-1) [ ] o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=2 deletes=0 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 1951820 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+asc&wt=xml} hits=6 status=0 QTime=3
[junit4] 2> 1951821 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=6&wt=xml} status=0 QTime=0
[junit4] 2> 1951821 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (15)]} 0 0
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (16)]} 0 0
[junit4] 2> 1951822 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.UpdateLog Dropping buffered updates FSUpdateLog{state=BUFFERING, tlog=null}
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C301 (13)]} 0 4
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C302 (14)]} 0 0
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1656503512149786624,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1951836 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:1656503512149786624
[junit4] 2> 1954274 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9f81232[collection1] main]
[junit4] 2> 1954275 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1954277 INFO (searcherExecutor-9061-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9f81232[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764851968, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_1(9.0.0):C4:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764853102, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]) Uninverting(_2(9.0.0):C2:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=x86_64, java.runtime.version=12.0.2+10, os.version=10.14.6, os=Mac OS X, java.vendor=AdoptOpenJDK, timestamp=1579764854585, java.version=12.0.2, java.vm.version=12.0.2+10}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1954277 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2440
[junit4] 2> 1954278 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/get&getVersions=2&wt=xml} status=0 QTime=0
[junit4] 2> 1954347 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&fl=id,_version_&sort=_version_+desc&rows=2&wt=xml} hits=8 status=0 QTime=69
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[C2 (14)]} 0 2
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1954351 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=802503257
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@44d42228
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=SolrCore@44d42228
[junit4] 2> 1954388 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c424011: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5737815b
[junit4] 2> 1954406 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() .
[junit4] 2> 1954406 INFO (coreCloseExecutor-9066-thread-1) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@51777078 commitCommandVersion:0
[junit4] 2> 1958610 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1958610 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5adddeb4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@21b7e0f9
[junit4] 2> 1958637 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1958637 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2d26f5b1: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3e2144e1
[junit4] 2> 1958644 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1958644 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8a8ca8c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11e5f819
[junit4] 2> 1958675 INFO (TEST-TestRecovery.testDropBuffered-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDropBuffered
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBufferedMultipleCalls
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.UpdateLog
[junit4] 2> 1958694 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1958695 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib, /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1958886 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1958920 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J4/temp/solr.search.TestRecovery_6042CA86275A0531-001/data-dir-207-001
[junit4] 2> 1958926 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 1959111 INFO (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 1959405 WARN (TEST-TestRecovery.testBufferedMultipleCalls-seed#[6042CA86275A0531]) [ ] o.a.s.f.DistribPackageStore Unable to create [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1044) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1323) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1355) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:619) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:180) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[java/:?]
[junit4] 2> at org.apache.solr.search.TestRecovery.beforeTest(TestRecovery.java:80) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:567) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:976) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.S
[...truncated too long message...]
/0x00000001441d09b8.run(Unknown Source)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 8) Thread[id=30044, name=recoveryExecutor-9319-thread-1-processing-x:collection1, state=BLOCKED, group=TGRP-TestRecovery]
[junit4] 2> at app//org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:127)
[junit4] 2> at app//org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:652)
[junit4] 2> at app//org.apache.solr.update.UpdateLog$LogReplayer.doReplay(UpdateLog.java:1957)
[junit4] 2> at app//org.apache.solr.update.UpdateLog$LogReplayer.run(UpdateLog.java:1752)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] 2> at app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:210)
[junit4] 2> at app//org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$$Lambda$216/0x00000001441d09b8.run(Unknown Source)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> 9) Thread[id=30043, name=searcherExecutor-9317-thread-1, state=WAITING, group=TGRP-TestRecovery]
[junit4] 2> at java.base@12.0.2/jdk.internal.misc.Unsafe.park(Native Method)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.locks.LockSupport.park(LockSupport.java:194)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2081)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:433)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
[junit4] 2> at java.base@12.0.2/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] 2> at java.base@12.0.2/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {val_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), _root_=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{_version_=DocValuesFormat(name=Lucene80), val_i=DocValuesFormat(name=Asserting), val_i_dvo=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=520, maxMBSortInHeap=7.668626555638418, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1b721744), locale=pa-Guru, timezone=America/Guyana
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=12,free=221652648,total=391081984
[junit4] 2> NOTE: All tests run in this JVM: [PrimitiveFieldTypeTest, SuggestComponentTest, TestShardHandlerFactory, LeaderFailureAfterFreshStartTest, TestWordDelimiterFilterFactory, CollectionReloadTest, TestReplicaProperties, DirectSolrConnectionTest, RollingRestartTest, TestTermsQParserPlugin, TestExactSharedStatsCacheCloud, TestElisionMultitermQuery, SpellCheckCollatorWithCollapseTest, HLLUtilTest, FieldAnalysisRequestHandlerTest, TestLegacyNumericRangeQueryBuilder, TestSolrCoreSnapshots, ForceLeaderWithTlogReplicasTest, TestStressThreadBackup, IndexSizeTriggerSizeEstimationTest, TestPackages, TestRestManager, TestSolrJ, BJQParserTest, AnalysisErrorHandlingTest, MetricsHistoryWithAuthIntegrationTest, TestSubQueryTransformer, HdfsTlogReplayBufferedWhileIndexingTest, DateRangeFieldTest, TestHighFrequencyDictionaryFactory, TestRTimerTree, TestIBSimilarityFactory, SampleTest, ChaosMonkeyNothingIsSafeTest, HdfsBasicDistributedZk2Test, SuggesterFSTTest, TestNestedUpdateProcessor, TestSimNodeLostTrigger, HttpPartitionWithTlogReplicasTest, RulesTest, TestStressRecovery, WrapperMergePolicyFactoryTest, DistributedFacetExistsSmallTest, TestImplicitCoreProperties, PreAnalyzedUpdateProcessorTest, HdfsDirectoryFactoryTest, TestFieldCacheReopen, TestFoldingMultitermQuery, QueryEqualityTest, CurrencyRangeFacetCloudTest, TestTestInjection, TestRestoreCore, SystemLogListenerTest, ShardRoutingTest, CustomHighlightComponentTest, PeerSyncWithLeaderTest, TestRetrieveFieldsOptimizer, DistributedSpellCheckComponentTest, TestFieldCollectionResource, CachingDirectoryFactoryTest, TestHttpServletCarrier, DefaultValueUpdateProcessorTest, TestManagedSchema, TestFunctionQuery, TestPerFieldSimilarity, CreateCollectionCleanupTest, DOMUtilTest, TestDistribIDF, CoreAdminOperationTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestMergePolicyConfig, TestExpandComponent, TestQueryTypes, TestSolrCloudWithKerberosAlt, SearchHandlerTest, TestSchemaResource, TestDeleteCollectionOnDownNodes, MoveReplicaTest, TestNoOpRegenerator, PackageManagerCLITest, TestSegmentSorting, AliasIntegrationTest, TestWaitForStateWithJettyShutdowns, SolrLogAuditLoggerPluginTest, TestCustomStream, TestJoin, TestRandomFaceting, TestTrie, TokenizerChainTest, TestLuceneIndexBackCompat, ChaosMonkeySafeLeaderWithPullReplicasTest, CloudExitableDirectoryReaderTest, CreateRoutedAliasTest, DeleteStatusTest, DistribJoinFromCollectionTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerRolesTest, RecoveryAfterSoftCommitTest, RecoveryZkTest, RemoteQueryErrorTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCLIZkUtilsTest, SolrCloudExampleTest, TestCloudPseudoReturnFields, TestCloudRecovery, TestCloudSearcherWarming, TestPullReplica, TestPullReplicaErrorHandling, TestStressCloudBlindAtomicUpdates, TestZkChroot, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, ZkControllerTest, ZkFailoverTest, CollectionsAPIDistributedZkTest, AutoScalingHandlerTest, IndexSizeTriggerTest, MetricTriggerIntegrationTest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, RestoreTriggerStateTest, TriggerCooldownIntegrationTest, TestSimExecutePlanAction, TestSimPolicyCloud, TestSimTriggerIntegration, CdcrOpsAndBoundariesTest, CdcrReplicationHandlerTest, HdfsBasicDistributedZkTest, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest, HdfsNNFailoverTest, PluginInfoTest, QueryResultKeyTest, SolrCoreCheckLockOnStartupTest, TestConfig, TestConfigOverlay, TestConfigSetImmutable, JsonLoaderTest, PingRequestHandlerTest, TestHdfsBackupRestoreCore, CoreMergeIndexesAdminHandlerTest, IndexSizeEstimatorTest, StatsReloadRaceTest, DistributedFacetPivotLargeTest, DistributedTermsComponentTest, TestNumericTokenStream, TestLogWatcher, JvmMetricsTest, SolrJmxReporterCloudTest, TestWriterPerf, TestBinaryResponseWriter, TestGeoJSONResponseWriter, TestSchemaVersionResource, TestManagedSynonymGraphFilterFactory, BooleanFieldTest, EnumFieldTest, IndexSchemaTest, NotRequiredUniqueKeyTest, SpatialRPTFieldTypeTest, TestManagedSchemaAPI, TestSchemaField, TestSchemaManager, TestSchemalessBufferedUpdates, TestSortableTextField, TestTextField, TestUseDocValuesAsStored, TestReRankQParserPlugin, TestRealTimeGet, TestRecovery]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRecovery -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J4 | TestRecovery (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([6042CA86275A0531]:0)
[junit4] Completed [900/900 (1!)] on J4 in 7212.90s, 9 tests, 2 errors <<< FAILURES!
[...truncated 44662 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5562/consoleText
[repro] Revision: c53cbb12f4df045432d9b3a831568d9543d81c88
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestRecovery -Dtests.method=testExistOldBufferLog -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestRecovery -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestRecovery
[repro] ant compile-test
[...truncated 3407 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestRecovery" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=6042CA86275A0531 -Dtests.slow=true -Dtests.locale=pa-Guru -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 176 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.search.TestRecovery
[repro] Exiting with code 0
[...truncated 73 lines...]