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/06/08 04:41:06 UTC
[JENKINS-EA] Lucene-Solr-8.x-Windows (64bit/jdk-15-ea+24) - Build #
1233 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1233/
Java: 64bit/jdk-15-ea+24 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
Error Message:
Error from server at http://127.0.0.1:65089/solr: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 401 require authentication</title> </head> <body><h2>HTTP ERROR 401 require authentication</h2> <table> <tr><th>URI:</th><td>/solr/authCollection/select</td></tr> <tr><th>STATUS:</th><td>401</td></tr> <tr><th>MESSAGE:</th><td>require authentication</td></tr> <tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-66f73e1a</td></tr> </table> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:65089/solr: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
<title>Error 401 require authentication</title>
</head>
<body><h2>HTTP ERROR 401 require authentication</h2>
<table>
<tr><th>URI:</th><td>/solr/authCollection/select</td></tr>
<tr><th>STATUS:</th><td>401</td></tr>
<tr><th>MESSAGE:</th><td>require authentication</td></tr>
<tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-66f73e1a</td></tr>
</table>
<hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([B57893E1E9BAFAA1:A7B84FA858532CA9]:0)
at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:686)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:411)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:746)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode(BasicAuthOnSingleNodeTest.java:83)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
Error Message:
Error from server at http://127.0.0.1:50589/solr: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 401 require authentication</title> </head> <body><h2>HTTP ERROR 401 require authentication</h2> <table> <tr><th>URI:</th><td>/solr/authCollection/select</td></tr> <tr><th>STATUS:</th><td>401</td></tr> <tr><th>MESSAGE:</th><td>require authentication</td></tr> <tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-33ea0fde</td></tr> </table> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50589/solr: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
<title>Error 401 require authentication</title>
</head>
<body><h2>HTTP ERROR 401 require authentication</h2>
<table>
<tr><th>URI:</th><td>/solr/authCollection/select</td></tr>
<tr><th>STATUS:</th><td>401</td></tr>
<tr><th>MESSAGE:</th><td>require authentication</td></tr>
<tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-33ea0fde</td></tr>
</table>
<hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([B57893E1E9BAFAA1:A7B84FA858532CA9]:0)
at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:686)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:411)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:746)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode(BasicAuthOnSingleNodeTest.java:83)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 13961 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthOnSingleNodeTest
[junit4] 2> 475556 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 475556 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 475556 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\data-dir-17-001
[junit4] 2> 475556 WARN (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 475556 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 475564 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) 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)
[junit4] 2> 475566 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDeleteSecurityJsonZnode
[junit4] 2> 475567 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001
[junit4] 2> 475567 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 475569 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 475569 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 475670 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer start zk server on port: 50576
[junit4] 2> 475670 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50576
[junit4] 2> 475670 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50576
[junit4] 2> 475670 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50576
[junit4] 2> 475673 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 475693 INFO (zkConnectionManagerCallback-2912-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 475693 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 475709 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 475749 INFO (zkConnectionManagerCallback-2914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 475749 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 475749 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 475901 INFO (zkConnectionManagerCallback-2916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 475901 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 476052 WARN (jetty-launcher-2917-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 476052 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 476052 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 476053 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+24-1168
[junit4] 2> 476053 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 476053 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 476053 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 476054 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6ef1ed51{/solr,null,AVAILABLE}
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@431fc21e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50589}
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.e.j.s.Server Started @475979ms
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50589}
[junit4] 2> 476058 ERROR (jetty-launcher-2917-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 476058 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-08T03:51:44.611159Z
[junit4] 2> 476059 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 476063 INFO (zkConnectionManagerCallback-2919-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 476063 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 476064 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 477140 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 477142 WARN (jetty-launcher-2917-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6bf9ab19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 477142 WARN (jetty-launcher-2917-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6bf9ab19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 477146 WARN (jetty-launcher-2917-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d7030df[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 477146 WARN (jetty-launcher-2917-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d7030df[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 477147 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50576/solr
[junit4] 2> 477148 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 477153 INFO (zkConnectionManagerCallback-2930-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 477153 INFO (jetty-launcher-2917-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 477256 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 477261 INFO (zkConnectionManagerCallback-2932-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 477262 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 477455 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50589_solr
[junit4] 2> 477458 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.Overseer Overseer (id=72067217158373381-127.0.0.1:50589_solr-n_0000000000) starting
[junit4] 2> 477467 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50589_solr
[junit4] 2> 477468 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50589_solr
[junit4] 2> 477470 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 477472 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 477472 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 477490 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 477531 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 477555 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 477557 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 477557 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 477562 INFO (jetty-launcher-2917-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1
[junit4] 2> 477575 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 477576 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 477578 INFO (zkConnectionManagerCallback-2949-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 477578 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 477579 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 477581 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50576/solr ready
[junit4] 2> 477599 INFO (qtp1306015189-4992) [n:127.0.0.1:50589_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=authCollection&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 477603 INFO (OverseerThreadFactory-2939-thread-1-processing-n:127.0.0.1:50589_solr) [n:127.0.0.1:50589_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 477719 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 477719 INFO (qtp1306015189-4996) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:50589_solr / MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAh9/8pPzTxKrTFCvUtt7DOYtRSSCIlUNsTKzHwTE8qhfJFhAnYQDgg/i2G8HDpbqGk9gskyjssATfD0AQ3XCUDqI4mO9UdoeR9kVXxxs3U8I748RLuQpT70vTuU859Jdt52j5C9++M7HP7pAud9WtsK/mtq6rP8S8wpEcDVHpLc1zA7m/SPIxjV7Aus46aqI6UKMTPdHm3YDZvrYDDQWvGFkj5I+ZEP2jA5rIdMm5MH5fx0N440K747WZFhuipSkKE1FvIhFxWTOwR4qcGjFTQgSn4C+uAoxvmTq/WB7wi2/9ZPsbGovxijxaxcumWDsKdA8WMNjFkNbBUxrqs78uWQIDAQAB
[junit4] 2> 477724 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 477726 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 477735 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 477743 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"authCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50589/solr",
[junit4] 2> "node_name":"127.0.0.1:50589_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 477747 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"authCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50589/solr",
[junit4] 2> "node_name":"127.0.0.1:50589_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 477750 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"authCollection_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50589/solr",
[junit4] 2> "node_name":"127.0.0.1:50589_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 477755 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"authCollection_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:50589/solr",
[junit4] 2> "node_name":"127.0.0.1:50589_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 477958 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr x:authCollection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=4&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 477958 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr x:authCollection_shard4_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=authCollection_shard4_replica_n6&action=CREATE&numShards=4&collection=authCollection&shard=shard4&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 477961 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr x:authCollection_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=4&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 477962 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr x:authCollection_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=4&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 477981 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 477981 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 477981 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 477995 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 478014 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 478016 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 478017 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 478019 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 478019 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 478019 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 478019 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard4_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 478020 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 478020 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard1_replica_n1\data\]
[junit4] 2> 478020 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 478020 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 478020 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 478021 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.core.authCollection.shard2.replica_n2' (registry 'solr.core.authCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 478021 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [[authCollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard2_replica_n2\data\]
[junit4] 2> 478039 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 478039 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4' using configuration from configset conf, trusted=true
[junit4] 2> 478039 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.core.authCollection.shard4.replica_n6' (registry 'solr.core.authCollection.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 478039 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrCore [[authCollection_shard4_replica_n6] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard4_replica_n6], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard4_replica_n6\data\]
[junit4] 2> 478057 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50589.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 478057 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-001\node1\authCollection_shard3_replica_n4\data\]
[junit4] 2> 478206 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 478206 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 478218 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 478219 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 478221 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 478221 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 478223 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 478223 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 478224 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 478224 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 478225 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 478225 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 478228 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 478228 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 478228 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 478228 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 478228 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 478228 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 478229 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 478229 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668901300372242432
[junit4] 2> 478231 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 478231 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 478231 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668901300374339584
[junit4] 2> 478232 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 478232 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 478232 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668901300375388160
[junit4] 2> 478232 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668901300375388160
[junit4] 2> 478232 INFO (searcherExecutor-2955-thread-1-processing-n:127.0.0.1:50589_solr x:authCollection_shard2_replica_n2 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 478233 INFO (searcherExecutor-2953-thread-1-processing-n:127.0.0.1:50589_solr x:authCollection_shard4_replica_n6 c:authCollection s:shard4 r:core_node8) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrCore [authCollection_shard4_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 478233 INFO (searcherExecutor-2951-thread-1-processing-n:127.0.0.1:50589_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node3) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 478235 INFO (searcherExecutor-2957-thread-1-processing-n:127.0.0.1:50589_solr x:authCollection_shard3_replica_n4 c:authCollection s:shard3 r:core_node7) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 478236 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 478237 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard2
[junit4] 2> 478240 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard4 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 478240 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard4
[junit4] 2> 478241 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 478244 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 478244 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard1
[junit4] 2> 478245 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 478247 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard3 to Terms{values={core_node7=0}, version=0}
[junit4] 2> 478247 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard3
[junit4] 2> 478247 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 478247 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 478247 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50589/solr/authCollection_shard4_replica_n6/
[junit4] 2> 478247 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50589/solr/authCollection_shard2_replica_n2/
[junit4] 2> 478247 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 478249 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 478250 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 478250 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 478250 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50589/solr/authCollection_shard1_replica_n1/
[junit4] 2> 478250 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy http://127.0.0.1:50589/solr/authCollection_shard4_replica_n6/ has no replicas
[junit4] 2> 478250 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard4/leader after winning as /collections/authCollection/leader_elect/shard4/election/72067217158373381-core_node8-n_0000000000
[junit4] 2> 478250 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 478250 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:50589/solr/authCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 478250 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard2/leader after winning as /collections/authCollection/leader_elect/shard2/election/72067217158373381-core_node5-n_0000000000
[junit4] 2> 478251 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:50589/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 478251 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard1/leader after winning as /collections/authCollection/leader_elect/shard1/election/72067217158373381-core_node3-n_0000000000
[junit4] 2> 478253 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50589/solr/authCollection_shard4_replica_n6/ shard4
[junit4] 2> 478253 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 478253 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 478253 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50589/solr/authCollection_shard3_replica_n4/
[junit4] 2> 478254 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50589/solr/authCollection_shard2_replica_n2/ shard2
[junit4] 2> 478254 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 478254 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy http://127.0.0.1:50589/solr/authCollection_shard3_replica_n4/ has no replicas
[junit4] 2> 478254 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard3/leader after winning as /collections/authCollection/leader_elect/shard3/election/72067217158373381-core_node7-n_0000000000
[junit4] 2> 478255 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50589/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 478256 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50589/solr/authCollection_shard3_replica_n4/ shard3
[junit4] 2> 478359 INFO (zkCallback-2931-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478359 INFO (zkCallback-2931-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478359 INFO (zkCallback-2931-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478361 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 478365 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 478366 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 478369 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 478374 INFO (qtp1306015189-4996) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=authCollection_shard4_replica_n6&action=CREATE&numShards=4&collection=authCollection&shard=shard4&wt=javabin&version=2&replicaType=NRT} status=0 QTime=414
[junit4] 2> 478374 INFO (qtp1306015189-5035) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=4&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=412
[junit4] 2> 478374 INFO (qtp1306015189-4995) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=4&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=413
[junit4] 2> 478376 INFO (qtp1306015189-4993) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=4&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=415
[junit4] 2> 478377 INFO (qtp1306015189-4992) [n:127.0.0.1:50589_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 478474 INFO (zkCallback-2931-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478474 INFO (zkCallback-2931-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478474 INFO (zkCallback-2931-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478474 INFO (zkCallback-2931-thread-5) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 478475 INFO (qtp1306015189-4992) [n:127.0.0.1:50589_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=100&name=authCollection&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2} status=0 QTime=879
[junit4] 2> 478476 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: authCollection
[junit4] 2> 478480 WARN (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@c81e8a0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 478480 WARN (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@c81e8a0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 478497 INFO (zkCallback-2931-thread-5) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json
[junit4] 2> 478602 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1294588374
[junit4] 2> 478602 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50589_solr
[junit4] 2> 478603 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 478603 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50589_solr as DOWN
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-2) [n:127.0.0.1:50589_solr ] o.a.s.c.SolrCore [authCollection_shard4_replica_n6] CLOSING SolrCore org.apache.solr.core.SolrCore@2277bd03
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-3) [n:127.0.0.1:50589_solr ] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@52b95756
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-2) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard4.replica_n6 tag=SolrCore@2277bd03
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@29b5a60e
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-2) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@39b95e54: rootName = solr_50589, domain = solr.core.authCollection.shard4.replica_n6, service url = null, agent id = null] for registry solr.core.authCollection.shard4.replica_n6/com.codahale.metrics.MetricRegistry@71054da7
[junit4] 2> 478605 INFO (coreCloseExecutor-2979-thread-4) [n:127.0.0.1:50589_solr ] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] CLOSING SolrCore org.apache.solr.core.SolrCore@4976daa0
[junit4] 2> 478614 INFO (coreCloseExecutor-2979-thread-2) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard4.leader tag=SolrCore@2277bd03
[junit4] 2> 478614 INFO (coreCloseExecutor-2979-thread-3) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard1.replica_n1 tag=SolrCore@52b95756
[junit4] 2> 478614 INFO (coreCloseExecutor-2979-thread-3) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@51a6a42b: rootName = solr_50589, domain = solr.core.authCollection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.authCollection.shard1.replica_n1/com.codahale.metrics.MetricRegistry@40012e9f
[junit4] 2> 478614 INFO (coreCloseExecutor-2979-thread-2) [n:127.0.0.1:50589_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 478622 INFO (coreCloseExecutor-2979-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard2.replica_n2 tag=SolrCore@29b5a60e
[junit4] 2> 478622 INFO (coreCloseExecutor-2979-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4d1b033a: rootName = solr_50589, domain = solr.core.authCollection.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.authCollection.shard2.replica_n2/com.codahale.metrics.MetricRegistry@4aa81090
[junit4] 2> 478629 INFO (coreCloseExecutor-2979-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard2.leader tag=SolrCore@29b5a60e
[junit4] 2> 478629 INFO (coreCloseExecutor-2979-thread-4) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard3.replica_n4 tag=SolrCore@4976daa0
[junit4] 2> 478629 INFO (coreCloseExecutor-2979-thread-4) [n:127.0.0.1:50589_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@633b67b5: rootName = solr_50589, domain = solr.core.authCollection.shard3.replica_n4, service url = null, agent id = null] for registry solr.core.authCollection.shard3.replica_n4/com.codahale.metrics.MetricRegistry@3a508766
[junit4] 2> 478629 INFO (coreCloseExecutor-2979-thread-1) [n:127.0.0.1:50589_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 478639 INFO (coreCloseExecutor-2979-thread-4) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard3.leader tag=SolrCore@4976daa0
[junit4] 2> 478639 INFO (coreCloseExecutor-2979-thread-3) [n:127.0.0.1:50589_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard1.leader tag=SolrCore@52b95756
[junit4] 2> 478639 INFO (coreCloseExecutor-2979-thread-4) [n:127.0.0.1:50589_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 478640 INFO (coreCloseExecutor-2979-thread-3) [n:127.0.0.1:50589_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 478641 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 478641 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b796c36: rootName = solr_50589, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@b754329
[junit4] 2> 478645 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 478645 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3eb71cd7: rootName = solr_50589, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1c167a72
[junit4] 2> 478648 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 478648 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c8f08ca: rootName = solr_50589, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@51f310da
[junit4] 2> 478648 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 478649 INFO (closeThreadPool-2981-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067217158373381-127.0.0.1:50589_solr-n_0000000000) closing
[junit4] 2> 478649 INFO (OverseerStateUpdate-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [n:127.0.0.1:50589_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50589_solr
[junit4] 2> 478650 INFO (OverseerAutoScalingTriggerThread-72067217158373381-127.0.0.1:50589_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 478650 INFO (closeThreadPool-2981-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72067217158373381-127.0.0.1:50589_solr-n_0000000000) closing
[junit4] 2> 478751 INFO (jetty-closer-2975-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067217158373381-127.0.0.1:50589_solr-n_0000000000) closing
[junit4] 2> 478752 INFO (jetty-closer-2975-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@431fc21e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 478753 INFO (jetty-closer-2975-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6ef1ed51{/solr,null,UNAVAILABLE}
[junit4] 2> 478753 INFO (jetty-closer-2975-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 478754 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 478961 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/authCollection/terms/shard2
[junit4] 2> 2 /solr/collections/authCollection/terms/shard1
[junit4] 2> 2 /solr/collections/authCollection/terms/shard4
[junit4] 2> 2 /solr/collections/authCollection/terms/shard3
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/authCollection/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 478965 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:50576
[junit4] 2> 478965 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50576
[junit4] 2> 478965 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50576
[junit4] 2> 480989 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteSecurityJsonZnode
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicAuthOnSingleNodeTest -Dtests.method=testDeleteSecurityJsonZnode -Dtests.seed=B57893E1E9BAFAA1 -Dtests.slow=true -Dtests.locale=khq -Dtests.timezone=Asia/Nicosia -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 5.43s J1 | BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50589/solr: Expected mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
[junit4] > <title>Error 401 require authentication</title>
[junit4] > </head>
[junit4] > <body><h2>HTTP ERROR 401 require authentication</h2>
[junit4] > <table>
[junit4] > <tr><th>URI:</th><td>/solr/authCollection/select</td></tr>
[junit4] > <tr><th>STATUS:</th><td>401</td></tr>
[junit4] > <tr><th>MESSAGE:</th><td>require authentication</td></tr>
[junit4] > <tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-33ea0fde</td></tr>
[junit4] > </table>
[junit4] > <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/>
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([B57893E1E9BAFAA1:A7B84FA858532CA9]:0)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:686)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:411)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:746)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode(BasicAuthOnSingleNodeTest.java:83)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 480993 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.SolrTestCaseJ4 ###Starting basicTest
[junit4] 2> 480994 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-002
[junit4] 2> 480994 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 480995 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 480995 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 481096 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer start zk server on port: 50706
[junit4] 2> 481096 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50706
[junit4] 2> 481096 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50706
[junit4] 2> 481096 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50706
[junit4] 2> 481098 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481102 INFO (zkConnectionManagerCallback-2983-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481102 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481104 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481106 INFO (zkConnectionManagerCallback-2985-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481106 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481107 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481109 INFO (zkConnectionManagerCallback-2987-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481109 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481214 WARN (jetty-launcher-2988-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 481214 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 481214 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 481214 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+24-1168
[junit4] 2> 481214 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 481214 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 481215 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 481215 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5cac0d06{/solr,null,AVAILABLE}
[junit4] 2> 481217 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e8a77d2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50723}
[junit4] 2> 481217 INFO (jetty-launcher-2988-thread-1) [ ] o.e.j.s.Server Started @481138ms
[junit4] 2> 481217 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50723}
[junit4] 2> 481217 ERROR (jetty-launcher-2988-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 481217 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 481218 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 481218 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 481218 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 481218 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-08T03:51:49.771293500Z
[junit4] 2> 481219 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481221 INFO (zkConnectionManagerCallback-2990-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481221 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481223 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 481435 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 481438 WARN (jetty-launcher-2988-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60285796[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 481438 WARN (jetty-launcher-2988-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60285796[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 481449 WARN (jetty-launcher-2988-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@46d6f527[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 481449 WARN (jetty-launcher-2988-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@46d6f527[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 481449 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50706/solr
[junit4] 2> 481451 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481458 INFO (zkConnectionManagerCallback-3001-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481458 INFO (jetty-launcher-2988-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481562 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481565 INFO (zkConnectionManagerCallback-3003-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481565 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481669 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50723_solr
[junit4] 2> 481669 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.Overseer Overseer (id=72067217513578501-127.0.0.1:50723_solr-n_0000000000) starting
[junit4] 2> 481677 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50723_solr
[junit4] 2> 481685 INFO (OverseerStateUpdate-72067217513578501-127.0.0.1:50723_solr-n_0000000000) [n:127.0.0.1:50723_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50723_solr
[junit4] 2> 481687 INFO (OverseerStateUpdate-72067217513578501-127.0.0.1:50723_solr-n_0000000000) [n:127.0.0.1:50723_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 481688 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 481689 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 481689 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 481714 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 481735 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50723.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 481739 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50723.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 481741 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50723.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2422be6e
[junit4] 2> 481741 INFO (jetty-launcher-2988-thread-1) [n:127.0.0.1:50723_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001\tempDir-002\node1
[junit4] 2> 481763 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 481765 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 481772 INFO (zkConnectionManagerCallback-3020-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 481772 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 481779 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 481781 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50706/solr ready
[junit4] 2> 481790 INFO (qtp1615697323-5111) [n:127.0.0.1:50723_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=authCollection&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 481794 INFO (OverseerThreadFactory-3010-thread-1-processing-n:127.0.0.1:50723_solr) [n:127.0.0.1:50723_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 481914 INFO (qtp1615697323-5110) [n:127.0.0.1:50723_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 481914 INFO (qtp1615697323-5109) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:50723_solr / MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEAvmvdlml+bthzQVRJGM51VIgjNRsmdM7PBq2E0C741Yz+rEvrFPEO7DPH71ii8KUwqTGtlsawbsRZwYngoruEYXCMGeC6sBSq0AFIXAHz4wK3M4qABpElhAAPYVl400nTV6CkNzIuvY3gr4jSrwXSW4hfpuJqehiyIKVkqQ+zEk5Vs0EeJ5nZLbhCBm39QWwhFrLwul2ppy9Ydfe6kg4HSAOUU0AHyFoA0ju2ctotbtEv+h8K/CZiOqmiYlyur/Pu6/iMZZDhQ6sQR9dDhaODaJfgFyVgYdvD4TJ0/XrJ7yKqv/gYSg1mYcdRpc7bmCdEURy+jfpyFeeWMim8yskzkQIDAQAB
[junit4] 2> 481914 INFO (qtp1615697323-5109) [n:127.0.0.1:50723_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 tr
[...truncated too long message...]
service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4d13d8b9
[junit4] 2> 36650 INFO (jetty-closer-137-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 36650 INFO (jetty-closer-137-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2a741d0: rootName = solr_65332, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@446f5979
[junit4] 2> 36653 INFO (jetty-closer-137-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 36653 INFO (jetty-closer-137-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c006f6e: rootName = solr_65332, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@73e6d7a4
[junit4] 2> 36658 INFO (jetty-closer-137-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 36666 WARN (OverseerStateUpdate-72067407593013253-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer Solr cannot talk to ZK, exiting Overseer main queue loop
[junit4] 2> => org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134)
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:134) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.multiInternal(ZooKeeper.java:1911) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.multi(ZooKeeper.java:1830) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$multi$10(SolrZkClient.java:591) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.multi(SolrZkClient.java:591) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.ZkDistributedQueue.remove(ZkDistributedQueue.java:246) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:267) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:832) [?:?]
[junit4] 2> 36666 INFO (OverseerStateUpdate-72067407593013253-127.0.0.1:65332_solr-n_0000000000) [n:127.0.0.1:65332_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:65332_solr
[junit4] 2> 36674 INFO (closeThreadPool-143-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067407593013253-127.0.0.1:65332_solr-n_0000000000) closing
[junit4] 2> 36675 INFO (OverseerAutoScalingTriggerThread-72067407593013253-127.0.0.1:65332_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 36685 INFO (closeThreadPool-143-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72067407593013253-127.0.0.1:65332_solr-n_0000000000) closing
[junit4] 2> 36765 INFO (jetty-closer-137-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72067407593013253-127.0.0.1:65332_solr-n_0000000000) closing
[junit4] 2> 36767 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6fd65181{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 36767 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@45e46166{/solr,null,UNAVAILABLE}
[junit4] 2> 36767 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 36768 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 36976 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/collections/authCollection/terms/shard2
[junit4] 2> 2 /solr/collections/authCollection/terms/shard1
[junit4] 2> 2 /solr/collections/authCollection/terms/shard4
[junit4] 2> 2 /solr/collections/authCollection/terms/shard3
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/authCollection/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 36980 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:65306
[junit4] 2> 36980 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:65306
[junit4] 2> 36980 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 65306
[junit4] 2> 39079 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[B57893E1E9BAFAA1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending basicTest
[junit4] OK 6.85s J0 | BasicAuthOnSingleNodeTest.basicTest
[junit4] 2> 39081 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 39081 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:65306
[junit4] 2> 39081 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:65306
[junit4] 2> 39081 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[B57893E1E9BAFAA1]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 65306
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J0\temp\solr.security.BasicAuthOnSingleNodeTest_B57893E1E9BAFAA1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1566, maxMBSortInHeap=6.332333026085356, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@18aedae1), locale=khq, timezone=Asia/Nicosia
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea (64-bit)/cpus=6,threads=2,free=48347656,total=169869312
[junit4] 2> NOTE: All tests run in this JVM: [BasicAuthOnSingleNodeTest]
[junit4] Completed [4/5 (4!)] on J0 in 38.55s, 2 tests, 1 error <<< FAILURES!
[...truncated 21 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1599: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1126: There were test failures: 5 suites, 10 tests, 4 errors [seed: B57893E1E9BAFAA1]
Total time: 44 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 4/5 failed: org.apache.solr.security.BasicAuthOnSingleNodeTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=b804cb4531b9b82d1395c7faea22d67db518e55a, workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows
[WARNINGS] Computing warning deltas based on reference build #1232
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS] Lucene-Solr-8.x-Windows (32bit/jdk1.8.0_201) - Build #
1234 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Windows/1234/
Java: 32bit/jdk1.8.0_201 -server -XX:+UseConcMarkSweepGC
7 tests failed.
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J1\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J0\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J0\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J4\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J4\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.lucene.store.TestRAFDirectory.testPendingDeletions
Error Message:
access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J2\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
Stack Trace:
java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J2\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
at java.security.AccessController.checkPermission(AccessController.java:884)
at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
at java.nio.file.Files.newOutputStream(Files.java:216)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
Error Message:
Error from server at http://127.0.0.1:59848/solr: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/> <title>Error 401 require authentication</title> </head> <body><h2>HTTP ERROR 401 require authentication</h2> <table> <tr><th>URI:</th><td>/solr/authCollection/select</td></tr> <tr><th>STATUS:</th><td>401</td></tr> <tr><th>MESSAGE:</th><td>require authentication</td></tr> <tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-a203ce</td></tr> </table> <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:59848/solr: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
<title>Error 401 require authentication</title>
</head>
<body><h2>HTTP ERROR 401 require authentication</h2>
<table>
<tr><th>URI:</th><td>/solr/authCollection/select</td></tr>
<tr><th>STATUS:</th><td>401</td></tr>
<tr><th>MESSAGE:</th><td>require authentication</td></tr>
<tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-a203ce</td></tr>
</table>
<hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([5E31F069E5176F7F:4CF12C2054FEB977]:0)
at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:686)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:411)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:746)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode(BasicAuthOnSingleNodeTest.java:83)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 9701 lines...]
[junit4] Suite: org.apache.lucene.store.TestRAFDirectory
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestRAFDirectory -Dtests.method=testPendingDeletions -Dtests.seed=6D3B269E8BFCF1E9 -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=Pacific/Pohnpei -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.06s J3 | TestRAFDirectory.testPendingDeletions <<<
[junit4] > Throwable #1: java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001\tempDir-003\prn.test" "write")
[junit4] > at __randomizedtesting.SeedInfo.seed([6D3B269E8BFCF1E9:F62751E6F39AFD4B]:0)
[junit4] > at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] > at java.security.AccessController.checkPermission(AccessController.java:884)
[junit4] > at java.lang.SecurityManager.checkPermission(SecurityManager.java:549)
[junit4] > at java.lang.SecurityManager.checkWrite(SecurityManager.java:979)
[junit4] > at sun.nio.fs.WindowsChannelFactory.open(WindowsChannelFactory.java:295)
[junit4] > at sun.nio.fs.WindowsChannelFactory.newFileChannel(WindowsChannelFactory.java:162)
[junit4] > at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(WindowsFileSystemProvider.java:225)
[junit4] > at java.nio.file.spi.FileSystemProvider.newOutputStream(FileSystemProvider.java:434)
[junit4] > at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
[junit4] > at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
[junit4] > at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
[junit4] > at org.apache.lucene.mockfile.HandleTrackingFS.newOutputStream(HandleTrackingFS.java:129)
[junit4] > at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
[junit4] > at org.apache.lucene.mockfile.FilterFileSystemProvider.newOutputStream(FilterFileSystemProvider.java:197)
[junit4] > at java.nio.file.Files.newOutputStream(Files.java:216)
[junit4] > at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:410)
[junit4] > at org.apache.lucene.store.FSDirectory$FSIndexOutput.<init>(FSDirectory.java:406)
[junit4] > at org.apache.lucene.store.FSDirectory.createOutput(FSDirectory.java:254)
[junit4] > at org.apache.lucene.store.BaseDirectoryTestCase.testPendingDeletions(BaseDirectoryTestCase.java:1167)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\build\misc\test\J3\temp\lucene.store.TestRAFDirectory_6D3B269E8BFCF1E9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=944, maxMBSortInHeap=5.634363389882896, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@64f00), locale=sr-Latn, timezone=Pacific/Pohnpei
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_201 (32-bit)/cpus=6,threads=1,free=40107744,total=64880640
[junit4] 2> NOTE: All tests run in this JVM: [TestDocValuesStatsCollector, TestRAFDirectory]
[junit4] Completed [15/16 (1!)] on J3 in 3.16s, 44 tests, 1 error <<< FAILURES!
[...truncated 6541 lines...]
[junit4] Suite: org.apache.solr.security.BasicAuthOnSingleNodeTest
[junit4] 2> 2393626 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 2393626 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\data-dir-193-001
[junit4] 2> 2393626 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2393636 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2393636 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2393638 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.SolrTestCaseJ4 ###Starting basicTest
[junit4] 2> 2393638 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001
[junit4] 2> 2393638 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2393641 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2393643 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2393742 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer start zk server on port: 59518
[junit4] 2> 2393742 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:59518
[junit4] 2> 2393742 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:59518
[junit4] 2> 2393742 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 59518
[junit4] 2> 2393760 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2393765 INFO (zkConnectionManagerCallback-17410-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2393765 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2393768 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2393772 INFO (zkConnectionManagerCallback-17412-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2393772 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2393773 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2393776 INFO (zkConnectionManagerCallback-17414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2393776 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2393880 WARN (jetty-launcher-17415-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2393880 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2393880 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2393880 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 2393880 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2393880 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2393882 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2393882 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e48c5d{/solr,null,AVAILABLE}
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1f2b96f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:59578}
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.e.j.s.Server Started @2393826ms
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59578}
[junit4] 2> 2393883 ERROR (jetty-launcher-17415-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr
[junit4] 2> 2393883 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-06-08T09:11:09.376Z
[junit4] 2> 2393884 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2393885 INFO (zkConnectionManagerCallback-17417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2393885 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2393885 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2394702 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2394706 WARN (jetty-launcher-17415-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@10d8c73[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2394706 WARN (jetty-launcher-17415-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@10d8c73[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2394710 WARN (jetty-launcher-17415-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6825cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2394710 WARN (jetty-launcher-17415-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6825cf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2394710 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59518/solr
[junit4] 2> 2394714 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2394714 INFO (zkConnectionManagerCallback-17428-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2394714 INFO (jetty-launcher-17415-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2394818 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2394820 INFO (zkConnectionManagerCallback-17430-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2394820 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2394898 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59578_solr
[junit4] 2> 2394898 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.Overseer Overseer (id=72068473112363013-127.0.0.1:59578_solr-n_0000000000) starting
[junit4] 2> 2394904 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:59578_solr
[junit4] 2> 2394904 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59578_solr
[junit4] 2> 2394905 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2394908 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2394908 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin
[junit4] 2> 2394908 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin
[junit4] 2> 2394970 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2395042 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395045 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395045 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395048 INFO (jetty-launcher-17415-thread-1) [n:127.0.0.1:59578_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1
[junit4] 2> 2395081 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 2395081 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2395081 INFO (zkConnectionManagerCallback-17447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2395081 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2395086 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2395088 INFO (TEST-BasicAuthOnSingleNodeTest.basicTest-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59518/solr ready
[junit4] 2> 2395092 INFO (qtp20270713-28070) [n:127.0.0.1:59578_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=authCollection&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2395095 INFO (OverseerThreadFactory-17437-thread-1-processing-n:127.0.0.1:59578_solr) [n:127.0.0.1:59578_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection
[junit4] 2> 2395210 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/info/key params={omitHeader=true&wt=json} status=0 QTime=0
[junit4] 2> 2395210 INFO (qtp20270713-28076) [ ] o.a.s.s.PKIAuthenticationPlugin New Key obtained from node: 127.0.0.1:59578_solr / MIIBIjANBgkqhkiG9w0BAQEFAAOCAQ8AMIIBCgKCAQEA3QYtrolSc41Bp8f/RFpBTeu9tq7qiLr/SscWPU2uzV/x8oXYUwmu4Op4CJo/l/Jo9DGcXDlhWqdXsP3bfIw3OIlOGz+pc3JXcf5JQdjGjhO0Dv7fLUo5Py+K9aCJ+XPKzyyQPNLSYnOQHeXqZRhzM5w0fkoBeZwX49nJyX+LRGbBpizzGIow6PrQBVoRkOIjZ9H+49ciBGb97AdxltbgW3RyZGy7pZl1j0qa8/i1JChBqVwcBW5VcfHL/eBFDmqiJygAk8RwTLBI90ZHzY21HFohIFDsnZC2U0g1hHqZeX3D5so19NALGEFGbyfuXDq2QK45P61Jxj+WkRajrY19cwIDAQAB
[junit4] 2> 2395210 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2395210 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 2395222 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 2395230 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"authCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59578/solr",
[junit4] 2> "node_name":"127.0.0.1:59578_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395234 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"authCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59578/solr",
[junit4] 2> "node_name":"127.0.0.1:59578_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395236 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"authCollection_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59578/solr",
[junit4] 2> "node_name":"127.0.0.1:59578_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395238 INFO (OverseerStateUpdate-72068473112363013-127.0.0.1:59578_solr-n_0000000000) [n:127.0.0.1:59578_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"authCollection",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"authCollection_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:59578/solr",
[junit4] 2> "node_name":"127.0.0.1:59578_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2395466 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr x:authCollection_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=4&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2395466 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr x:authCollection_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=authCollection_shard2_replica_n2&action=CREATE&numShards=4&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2395467 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr x:authCollection_shard4_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=authCollection_shard4_replica_n6&action=CREATE&numShards=4&collection=authCollection&shard=shard4&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2395467 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr x:authCollection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=authCollection_shard1_replica_n1&action=CREATE&numShards=4&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2395521 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 2395523 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 2395523 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 2395530 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 2395545 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2395545 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2395547 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2395551 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 2395558 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395558 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 2395558 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395558 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4' using configuration from configset conf, trusted=true
[junit4] 2> 2395561 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395561 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.core.authCollection.shard2.replica_n2' (registry 'solr.core.authCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395564 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [[authCollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard2_replica_n2\data\]
[junit4] 2> 2395564 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard3_replica_n4\data\]
[junit4] 2> 2395571 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395571 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2395571 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard4_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 2395571 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 2395571 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.core.authCollection.shard4.replica_n6' (registry 'solr.core.authCollection.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395571 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59578.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@8932a2
[junit4] 2> 2395571 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrCore [[authCollection_shard4_replica_n6] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard4_replica_n6], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard4_replica_n6\data\]
[junit4] 2> 2395571 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [[authCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J2\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001\tempDir-001\node1\authCollection_shard1_replica_n1\data\]
[junit4] 2> 2395758 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2395758 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395761 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2395761 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395761 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2395761 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395761 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2395761 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2395761 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395761 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2395762 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2395762 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2395762 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668921395778682880
[junit4] 2> 2395762 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2395762 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2395766 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2395766 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2395766 INFO (searcherExecutor-17455-thread-1-processing-n:127.0.0.1:59578_solr x:authCollection_shard4_replica_n6 c:authCollection s:shard4 r:core_node8) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SolrCore [authCollection_shard4_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2395768 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2395768 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2395768 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2395768 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2395768 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2395768 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668921395784974336
[junit4] 2> 2395768 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2395768 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668921395784974336
[junit4] 2> 2395768 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2395768 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1668921395784974336
[junit4] 2> 2395769 INFO (searcherExecutor-17454-thread-1-processing-n:127.0.0.1:59578_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node3) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [authCollection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2395770 INFO (searcherExecutor-17451-thread-1-processing-n:127.0.0.1:59578_solr x:authCollection_shard3_replica_n4 c:authCollection s:shard3 r:core_node7) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2395770 INFO (searcherExecutor-17450-thread-1-processing-n:127.0.0.1:59578_solr x:authCollection_shard2_replica_n2 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2395771 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard4 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 2395771 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard4
[junit4] 2> 2395774 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 2395774 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard2
[junit4] 2> 2395774 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395778 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard3 to Terms{values={core_node7=0}, version=0}
[junit4] 2> 2395778 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard3
[junit4] 2> 2395779 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395780 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 2395780 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395780 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395780 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59578/solr/authCollection_shard4_replica_n6/
[junit4] 2> 2395780 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59578/solr/authCollection_shard2_replica_n2/
[junit4] 2> 2395780 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard1
[junit4] 2> 2395780 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395780 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.SyncStrategy http://127.0.0.1:59578/solr/authCollection_shard4_replica_n6/ has no replicas
[junit4] 2> 2395780 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard4/leader after winning as /collections/authCollection/leader_elect/shard4/election/72068473112363013-core_node8-n_0000000000
[junit4] 2> 2395782 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395782 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:59578/solr/authCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 2395782 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard2/leader after winning as /collections/authCollection/leader_elect/shard2/election/72068473112363013-core_node5-n_0000000000
[junit4] 2> 2395782 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395782 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395782 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59578/solr/authCollection_shard3_replica_n4/
[junit4] 2> 2395782 INFO (qtp20270713-28071) [n:127.0.0.1:59578_solr c:authCollection s:shard4 r:core_node8 x:authCollection_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:59578/solr/authCollection_shard4_replica_n6/ shard4
[junit4] 2> 2395784 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395784 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy http://127.0.0.1:59578/solr/authCollection_shard3_replica_n4/ has no replicas
[junit4] 2> 2395784 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard3/leader after winning as /collections/authCollection/leader_elect/shard3/election/72068473112363013-core_node7-n_0000000000
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:59578/solr/authCollection_shard1_replica_n1/
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:59578/solr/authCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 2395784 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/authCollection/leaders/shard1/leader after winning as /collections/authCollection/leader_elect/shard1/election/72068473112363013-core_node3-n_0000000000
[junit4] 2> 2395785 INFO (qtp20270713-28075) [n:127.0.0.1:59578_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:59578/solr/authCollection_shard2_replica_n2/ shard2
[junit4] 2> 2395786 INFO (qtp20270713-28076) [n:127.0.0.1:59578_solr c:authCollection s:shard3 r:core_node7 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:59578/solr/authCollection_shard3_replica_n4/ shard3
[junit4] 2> 2395786 INFO (qtp20270713-28114) [n:127.0.0.1:59578_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:59578/solr/authCollection_shard1_replica_n1/ shard1
[junit4] 2> 2395890 INFO (zkCallback-17429-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/authCollection/state.json] for collection [authCollection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2395890 INFO (zkCallback-17429-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged p
[...truncated too long message...]
0000000) closing
[junit4] 2> 56279 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@537814{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 56280 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@12c0d0a{/solr,null,UNAVAILABLE}
[junit4] 2> 56280 INFO (jetty-closer-137-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 56281 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 56496 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/authCollection/terms/shard2
[junit4] 2> 2 /solr/collections/authCollection/terms/shard1
[junit4] 2> 2 /solr/collections/authCollection/terms/shard4
[junit4] 2> 2 /solr/collections/authCollection/terms/shard3
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/authCollection/state.json
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/live_nodes
[junit4] 2>
[junit4] 2> 56505 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:63016
[junit4] 2> 56505 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63016
[junit4] 2> 56505 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[5E31F069E5176F7F]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63016
[junit4] 2> 58511 INFO (TEST-BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode-seed#[5E31F069E5176F7F]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDeleteSecurityJsonZnode
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=BasicAuthOnSingleNodeTest -Dtests.method=testDeleteSecurityJsonZnode -Dtests.seed=5E31F069E5176F7F -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=zh-TW -Dtests.timezone=Europe/Monaco -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 6.00s J4 | BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:63058/solr: Expected mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=utf-8"/>
[junit4] > <title>Error 401 require authentication</title>
[junit4] > </head>
[junit4] > <body><h2>HTTP ERROR 401 require authentication</h2>
[junit4] > <table>
[junit4] > <tr><th>URI:</th><td>/solr/authCollection/select</td></tr>
[junit4] > <tr><th>STATUS:</th><td>401</td></tr>
[junit4] > <tr><th>MESSAGE:</th><td>require authentication</td></tr>
[junit4] > <tr><th>SERVLET:</th><td>org.apache.solr.client.solrj.embedded.JettySolrRunner$Servlet404-16d5a96</td></tr>
[junit4] > </table>
[junit4] > <hr><a href="http://eclipse.org/jetty">Powered by Jetty:// 9.4.27.v20200227</a><hr/>
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([5E31F069E5176F7F:4CF12C2054FEB977]:0)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.processErrorsAndResponse(Http2SolrClient.java:686)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:411)
[junit4] > at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:746)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode(BasicAuthOnSingleNodeTest.java:83)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 58521 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 58521 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:63016
[junit4] 2> 58521 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63016
[junit4] 2> 58521 INFO (SUITE-BasicAuthOnSingleNodeTest-seed#[5E31F069E5176F7F]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63016
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\solr\build\solr-core\test\J4\temp\solr.security.BasicAuthOnSingleNodeTest_5E31F069E5176F7F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=1771, maxMBSortInHeap=7.237580547712086, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@c4b339), locale=zh-TW, timezone=Europe/Monaco
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_201 (32-bit)/cpus=6,threads=2,free=40638488,total=92950528
[junit4] 2> NOTE: All tests run in this JVM: [BasicAuthOnSingleNodeTest]
[junit4] Completed [3/5 (2!)] on J4 in 58.23s, 2 tests, 1 error <<< FAILURES!
[junit4]
[junit4] Suite: org.apache.solr.security.BasicAuthOnSingleNodeTest
[junit4] OK 37.5s J1 | BasicAuthOnSingleNodeTest.basicTest
[junit4] OK 5.97s J1 | BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
[junit4] Completed [4/5 (2!)] on J1 in 58.58s, 2 tests
[junit4]
[junit4] Suite: org.apache.solr.security.BasicAuthOnSingleNodeTest
[junit4] OK 37.9s J0 | BasicAuthOnSingleNodeTest.basicTest
[junit4] OK 6.49s J0 | BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
[junit4] Completed [5/5 (2!)] on J0 in 59.08s, 2 tests
[junit4]
[junit4]
[junit4] Tests with failures [seed: 5E31F069E5176F7F]:
[junit4] - org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
[junit4] - org.apache.solr.security.BasicAuthOnSingleNodeTest.testDeleteSecurityJsonZnode
[junit4]
[junit4]
[junit4] JVM J0: 1.01 .. 62.23 = 61.22s
[junit4] JVM J1: 1.09 .. 61.75 = 60.66s
[junit4] JVM J2: 0.86 .. 60.92 = 60.06s
[junit4] JVM J3: 1.01 .. 61.28 = 60.26s
[junit4] JVM J4: 1.09 .. 61.30 = 60.22s
[junit4] Execution time total: 1 minute 2 seconds
[junit4] Tests summary: 5 suites, 10 tests, 2 errors
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1599: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows\lucene\common-build.xml:1126: There were test failures: 5 suites, 10 tests, 2 errors [seed: 5E31F069E5176F7F]
Total time: 1 minute 4 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 2/5 failed: org.apache.solr.security.BasicAuthOnSingleNodeTest
[repro] 5/5 failed: org.apache.lucene.store.TestRAFDirectory
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=b804cb4531b9b82d1395c7faea22d67db518e55a, workspace=C:\Users\jenkins\workspace\Lucene-Solr-8.x-Windows
[WARNINGS] Computing warning deltas based on reference build #1233
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2