Build: https://jenkins.thetaphi.de/job/Lucene-Solr-SOLR-13661-Windows/98/ Java: 64bit/jdk-11.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed. FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud Error Message: IOException occurred when talking to server at: https://127.0.0.1:56819/solr Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:56819/solr at __randomizedtesting.SeedInfo.seed([A1B34DBB130743CC:70B4BF3EB708C8FE]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:679) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368) at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296) at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1127) at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:896) at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:828) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224) at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:87) at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:79) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:566) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:834) Caused by: javax.net.ssl.SSLException: Software caused connection abort: recv failed at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258) at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1315) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:839) at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564) ... 49 more Suppressed: java.net.SocketException: Software caused connection abort: socket write error at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110) at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150) at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351) ... 71 more Caused by: java.net.SocketException: Software caused connection abort: recv failed at java.base/java.net.SocketInputStream.socketRead0(Native Method) at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115) at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168) at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823) ... 67 more FAILED: org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth Error Message: Software caused connection abort: recv failed Stack Trace: javax.net.ssl.SSLException: Software caused connection abort: recv failed at __randomizedtesting.SeedInfo.seed([A1B34DBB130743CC:1DDD3BA9B754C0B6]:0) at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258) at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1315) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:839) at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137) at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153) at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259) at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163) at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125) at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272) at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185) at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56) at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:199) at org.apache.solr.cloud.SolrCloudAuthTestCase.verifySecurityStatus(SolrCloudAuthTestCase.java:175) at org.apache.solr.security.BasicAuthIntegrationTest.testBasicAuth(BasicAuthIntegrationTest.java:132) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:566) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:834) Suppressed: java.net.SocketException: Software caused connection abort: socket write error at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110) at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150) at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81) at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351) ... 63 more Caused by: java.net.SocketException: Software caused connection abort: recv failed at java.base/java.net.SocketInputStream.socketRead0(Native Method) at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115) at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168) at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140) at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448) at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68) at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104) at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823) ... 59 more Build Log: [...truncated 13902 lines...] [junit4] Suite: org.apache.solr.security.BasicAuthIntegrationTest [junit4] 2> 1670670 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001 [junit4] 2> 1670676 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1670756 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1670756 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1670788 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkTestServer start zk server on port:61288 [junit4] 2> 1670788 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61288 [junit4] 2> 1670788 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61288 [junit4] 2> 1670788 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61288 [junit4] 2> 1670836 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1670856 INFO (zkConnectionManagerCallback-7516-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1670856 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1670860 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1670874 INFO (zkConnectionManagerCallback-7518-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1670874 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1670875 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1670886 INFO (zkConnectionManagerCallback-7520-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1670886 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671004 WARN (jetty-launcher-7521-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1671004 WARN (jetty-launcher-7521-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11 [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11 [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b086f03{/solr,null,AVAILABLE} [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 1671004 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c5904a4{/solr,null,AVAILABLE} [junit4] 2> 1671013 WARN (jetty-launcher-7521-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1671013 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 1671013 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 1671013 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11 [junit4] 2> 1671013 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1671013 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1671014 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1671014 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6a84a0d0{/solr,null,AVAILABLE} [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4673cda3{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61304} [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.e.j.s.Server Started @1671047ms [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61304} [junit4] 2> 1671017 ERROR (jetty-launcher-7521-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1671017 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-03T21:34:06.195242Z [junit4] 2> 1671019 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671025 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3e8bbf1d{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61306} [junit4] 2> 1671025 INFO (jetty-launcher-7521-thread-2) [ ] o.e.j.s.Server Started @1671056ms [junit4] 2> 1671025 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61306} [junit4] 2> 1671026 ERROR (jetty-launcher-7521-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1671026 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1671026 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1671026 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1671026 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1671026 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-03T21:34:06.204629Z [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@968e36a{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61310} [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.e.j.s.Server Started @1671057ms [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61310} [junit4] 2> 1671027 ERROR (jetty-launcher-7521-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1671027 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-03T21:34:06.205197600Z [junit4] 2> 1671028 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671032 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671036 INFO (zkConnectionManagerCallback-7523-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671036 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671039 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1671049 INFO (zkConnectionManagerCallback-7525-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671049 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671050 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1671052 INFO (zkConnectionManagerCallback-7527-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671052 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671344 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1671576 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1671580 WARN (jetty-launcher-7521-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2604535c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671580 WARN (jetty-launcher-7521-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2604535c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671590 WARN (jetty-launcher-7521-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@54ddfc78[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671590 WARN (jetty-launcher-7521-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@54ddfc78[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671590 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61288/solr [junit4] 2> 1671596 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671600 INFO (zkConnectionManagerCallback-7536-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671600 INFO (jetty-launcher-7521-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671664 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1671670 WARN (jetty-launcher-7521-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@14cb470f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671670 WARN (jetty-launcher-7521-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@14cb470f[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671680 WARN (jetty-launcher-7521-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1871e381[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671680 WARN (jetty-launcher-7521-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1871e381[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1671680 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61288/solr [junit4] 2> 1671684 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671689 INFO (zkConnectionManagerCallback-7542-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671689 INFO (jetty-launcher-7521-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671724 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671733 INFO (zkConnectionManagerCallback-7544-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671733 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1671794 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1671809 INFO (zkConnectionManagerCallback-7546-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1671809 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1672168 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61306_solr [junit4] 2> 1672174 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.Overseer Overseer (id=72098477251690504-127.0.0.1:61306_solr-n_0000000000) starting [junit4] 2> 1672204 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1672212 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61306_solr [junit4] 2> 1672219 WARN (jetty-launcher-7521-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@117501be[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1672219 WARN (jetty-launcher-7521-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@117501be[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1672236 INFO (zkCallback-7543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1672236 WARN (jetty-launcher-7521-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a1c180[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1672236 WARN (jetty-launcher-7521-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a1c180[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1672238 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61288/solr [junit4] 2> 1672239 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1672241 INFO (OverseerStateUpdate-72098477251690504-127.0.0.1:61306_solr-n_0000000000) [n:127.0.0.1:61306_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61306_solr [junit4] 2> 1672265 INFO (zkConnectionManagerCallback-7557-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1672265 INFO (jetty-launcher-7521-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1672284 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1672325 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61306.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672339 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61306.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672339 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61306.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672379 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1672380 INFO (jetty-launcher-7521-thread-2) [n:127.0.0.1:61306_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node2\. [junit4] 2> 1672391 INFO (zkConnectionManagerCallback-7560-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1672391 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1672544 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1672553 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61310_solr as DOWN [junit4] 2> 1672553 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1672553 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61310_solr [junit4] 2> 1672574 INFO (zkCallback-7543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1672597 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 1672608 INFO (zkCallback-7559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1672610 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61304_solr as DOWN [junit4] 2> 1672633 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1672633 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61304_solr [junit4] 2> 1672635 INFO (zkCallback-7559-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1672635 INFO (zkCallback-7543-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1672642 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1672660 INFO (zkCallback-7545-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 1672677 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 1672690 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61310.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672708 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61310.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672712 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61310.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672715 INFO (jetty-launcher-7521-thread-1) [n:127.0.0.1:61310_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node1\. [junit4] 2> 1672720 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61304.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672724 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61304.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672724 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61304.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1672742 INFO (jetty-launcher-7521-thread-3) [n:127.0.0.1:61304_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node3\. [junit4] 2> 1672920 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3 [junit4] 2> 1672920 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1672944 INFO (zkConnectionManagerCallback-7571-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1672944 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1672980 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 1672994 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61288/solr ready [junit4] 2> 1673028 INFO (qtp1284220627-23950) [n:127.0.0.1:61304_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 1673051 INFO (OverseerThreadFactory-8928-thread-1-processing-n:127.0.0.1:61306_solr) [n:127.0.0.1:61306_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection authCollection [junit4] 2> 1673177 INFO (OverseerStateUpdate-72098477251690504-127.0.0.1:61306_solr-n_0000000000) [n:127.0.0.1:61306_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":"https://127.0.0.1:61310/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1673212 INFO (OverseerStateUpdate-72098477251690504-127.0.0.1:61306_solr-n_0000000000) [n:127.0.0.1:61306_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":"https://127.0.0.1:61304/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1673222 INFO (OverseerStateUpdate-72098477251690504-127.0.0.1:61306_solr-n_0000000000) [n:127.0.0.1:61306_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":"https://127.0.0.1:61306/solr";, [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 1673512 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr x:authCollection_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1673512 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr x:authCollection_shard3_replica_n4 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 1673517 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1673517 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT [junit4] 2> 1674574 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1674604 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1674617 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema [authCollection_shard1_replica_n1] Schema name=minimal [junit4] 2> 1674619 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1674619 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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 collection authCollection, trusted=true [junit4] 2> 1674619 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61310.solr.core.authCollection.shard1.replica_n1' (registry 'solr.core.authCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1674619 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node1\authCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node1\.\authCollection_shard1_replica_n1\data\] [junit4] 2> 1674626 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema [authCollection_shard3_replica_n4] Schema name=minimal [junit4] 2> 1674627 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 1674627 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'authCollection_shard3_replica_n4' using configuration from collection authCollection, trusted=true [junit4] 2> 1674627 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61306.solr.core.authCollection.shard3.replica_n4' (registry 'solr.core.authCollection.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1674627 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0 [junit4] 2> 1674627 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [[authCollection_shard3_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node2\authCollection_shard3_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node2\.\authCollection_shard3_replica_n4\data\] [junit4] 2> 1674699 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema [authCollection_shard2_replica_n2] Schema name=minimal [junit4] 2> 1674700 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674700 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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 collection authCollection, trusted=true [junit4] 2> 1674701 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61304.solr.core.authCollection.shard2.replica_n2' (registry 'solr.core.authCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@410984e3 [junit4] 2> 1674701 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node3\authCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.security.BasicAuthIntegrationTest_A1B34DBB130743CC-001\tempDir-001\node3\.\authCollection_shard2_replica_n2\data\] [junit4] 2> 1674869 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1674869 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 1674875 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1674875 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1674888 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674888 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674899 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1674899 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1674900 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3018d81a[authCollection_shard3_replica_n4] main] [junit4] 2> 1674904 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 1674904 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 1674904 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1674904 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1651935910383583232 [junit4] 2> 1674904 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b7e509e[authCollection_shard2_replica_n2] main] [junit4] 2> 1674938 INFO (searcherExecutor-8941-thread-1-processing-n:127.0.0.1:61306_solr x:authCollection_shard3_replica_n4 c:authCollection s:shard3 r:core_node6) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SolrCore [authCollection_shard3_replica_n4] Registered new searcher Searcher@3018d81a[authCollection_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1674944 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674944 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1674944 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1674948 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674948 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1674948 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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 1651935910429720576 [junit4] 2> 1674950 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1674950 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1674953 INFO (searcherExecutor-8942-thread-1-processing-n:127.0.0.1:61304_solr x:authCollection_shard2_replica_n2 c:authCollection s:shard2 r:core_node5) [n:127.0.0.1:61304_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 Searcher@b7e509e[authCollection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1674954 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/authCollection/terms/shard3 to Terms{values={core_node6=0}, version=0} [junit4] 2> 1674954 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/authCollection/leaders/shard3 [junit4] 2> 1674956 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a724de3[authCollection_shard1_replica_n1] main] [junit4] 2> 1674977 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674977 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674980 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1674981 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1674982 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 1674982 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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 1651935910465372160 [junit4] 2> 1674984 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1674984 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 1674985 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61306/solr/authCollection_shard3_replica_n4/ [junit4] 2> 1674986 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1674986 INFO (searcherExecutor-8940-thread-1-processing-n:127.0.0.1:61310_solr x:authCollection_shard1_replica_n1 c:authCollection s:shard1 r:core_node3) [n:127.0.0.1:61310_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 Searcher@7a724de3[authCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1674986 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.SyncStrategy https://127.0.0.1:61306/solr/authCollection_shard3_replica_n4/ has no replicas [junit4] 2> 1674986 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 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/72098477251690504-core_node6-n_0000000000 [junit4] 2> 1674992 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674992 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674992 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61304/solr/authCollection_shard2_replica_n2/ [junit4] 2> 1674994 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1674994 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61306/solr/authCollection_shard3_replica_n4/ shard3 [junit4] 2> 1674994 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:61304/solr/authCollection_shard2_replica_n2/ has no replicas [junit4] 2> 1674994 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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/72098477251690505-core_node5-n_0000000000 [junit4] 2> 1675000 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675000 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675014 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61304/solr/authCollection_shard2_replica_n2/ shard2 [junit4] 2> 1675054 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675054 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675054 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:61310/solr/authCollection_shard1_replica_n1/ [junit4] 2> 1675064 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675072 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:61310/solr/authCollection_shard1_replica_n1/ has no replicas [junit4] 2> 1675072 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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/72098477251690507-core_node3-n_0000000000 [junit4] 2> 1675109 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:61310/solr/authCollection_shard1_replica_n1/ shard1 [junit4] 2> 1675222 INFO (zkCallback-7545-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: [3]) [junit4] 2> 1675222 INFO (zkCallback-7559-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: [3]) [junit4] 2> 1675222 INFO (zkCallback-7543-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: [3]) [junit4] 2> 1675223 INFO (zkCallback-7545-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: [3]) [junit4] 2> 1675223 INFO (zkCallback-7559-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: [3]) [junit4] 2> 1675223 INFO (zkCallback-7543-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: [3]) [junit4] 2> 1675238 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_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> 1675238 INFO (qtp59803674-23969) [n:127.0.0.1:61310_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> 1675240 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1675242 INFO (qtp59803674-23969) [n:127.0.0.1:61310_solr c:authCollection s:shard1 r:core_node3 x:authCollection_shard1_replica_n1 ] 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=3&collection=authCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1730 [junit4] 2> 1675249 INFO (qtp880006253-23957) [n:127.0.0.1:61306_solr c:authCollection s:shard3 r:core_node6 x:authCollection_shard3_replica_n4 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=conf&newCollection=true&name=authCollection_shard3_replica_n4&action=CREATE&numShards=3&collection=authCollection&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1735 [junit4] 2> 1675268 INFO (qtp1284220627-23974) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] 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=3&collection=authCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1752 [junit4] 2> 1675288 INFO (qtp1284220627-23950) [n:127.0.0.1:61304_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> 1675357 INFO (zkCallback-7545-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: [3]) [junit4] 2> 1675357 INFO (zkCallback-7545-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: [3]) [junit4] 2> 1675357 INFO (zkCallback-7559-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: [3]) [junit4] 2> 1675357 INFO (zkCallback-7559-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: [3]) [junit4] 2> 1675357 INFO (zkCallback-7543-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: [3]) [junit4] 2> 1675358 INFO (zkCallback-7543-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: [3]) [junit4] 2> 1675358 INFO (zkCallback-7543-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: [3]) [junit4] 2> 1675359 INFO (qtp1284220627-23950) [n:127.0.0.1:61304_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=authCollection&nrtReplicas=1&action=CREATE&numShards=3&wt=javabin&version=2} status=0 QTime=2323 [junit4] 2> 1675359 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: authCollection [junit4] 2> 1675361 INFO (zkCallback-7559-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: [3]) [junit4] 2> 1675361 INFO (zkCallback-7545-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: [3]) [junit4] 2> 1675376 INFO (qtp1284220627-23951) [n:127.0.0.1:61304_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 1675381 INFO (zkCallback-7543-thread-4) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 1675381 INFO (zkCallback-7543-thread-4) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 1675381 INFO (zkCallback-7543-thread-4) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 1675381 INFO (zkCallback-7559-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 1675381 INFO (zkCallback-7559-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 1675381 INFO (zkCallback-7559-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 1675381 INFO (zkCallback-7545-thread-3) [ ] o.a.s.c.CoreContainer Security node changed, reloading security.json [junit4] 2> 1675381 INFO (zkCallback-7545-thread-3) [ ] o.a.s.c.CoreContainer Initializing authorization plugin: solr.RuleBasedAuthorizationPlugin [junit4] 2> 1675381 INFO (zkCallback-7545-thread-3) [ ] o.a.s.c.CoreContainer Initializing authentication plugin: solr.BasicAuthPlugin [junit4] 2> 1675384 DEBUG (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.PKIAuthenticationPlugin HttpHeaderClientInterceptor secures this internode request [junit4] 2> 1675384 DEBUG (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.PKIAuthenticationPlugin HttpHeaderClientInterceptor secures this internode request [junit4] 2> 1675384 DEBUG (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.PKIAuthenticationPlugin HttpHeaderClientInterceptor secures this internode request [junit4] 2> 1675385 DEBUG (qtp1284220627-23962) [n:127.0.0.1:61304_solr ] o.a.s.s.RuleBasedAuthorizationPlugin Attempting to authorize request to [/admin/authentication] of type: [ADMIN], associated with collections [[]] [junit4] 2> 1675385 DEBUG (qtp1284220627-23962) [n:127.0.0.1:61304_solr ] o.a.s.s.RuleBasedAuthorizationPlugin Authorizing an ADMIN request, checking admin permissions [junit4] 2> 1675385 DEBUG (qtp1284220627-23962) [n:127.0.0.1:61304_solr ] o.a.s.s.RuleBasedAuthorizationPlugin No perms configured for the resource /admin/authentication . So allowed to access [junit4] 2> 1675385 INFO (qtp1284220627-23962) [n:127.0.0.1:61304_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/authentication params={} status=0 QTime=0 [junit4] 2> 1675386 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=24046470 [junit4] 2> 1675386 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:61304_solr [junit4] 2> 1675388 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkController Publish this node as DOWN... [junit4] 2> 1675388 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:61304_solr as DOWN [junit4] 2> 1675392 INFO (coreCloseExecutor-8955-thread-1) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [authCollection_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@6a1a8f4c [junit4] 2> 1675392 INFO (coreCloseExecutor-8955-thread-1) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.authCollection.shard2.replica_n2, tag=SolrCore@6a1a8f4c [junit4] 2> 1675392 INFO (coreCloseExecutor-8955-thread-1) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2c31b3b0: rootName = solr_61304, 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@4bdbc18e [junit4] 2> 1675419 INFO (coreCloseExecutor-8955-thread-1) [n:127.0.0.1:61304_solr c:authCollection s:shard2 r:core_node5 x:authCollection_shard2_replica_n2 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.authCollection.shard2.leader, tag=SolrCore@6a1a8f4c [junit4] 2> 1675426 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null [junit4] 2> 1675426 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4601ec50: rootName = solr_61304, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@339318fd [junit4] 2> 1675454 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null [junit4] 2> 1675454 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@117bb7b9: rootName = solr_61304, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@77cacf57 [junit4] 2> 1675459 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null [junit4] 2> 1675459 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4fb06eff: rootName = solr_61304, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@11f5a067 [junit4] 2> 1675459 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null [junit4] 2> 1675463 INFO (zkCallback-7543-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1675463 INFO (zkCallback-7559-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1675463 INFO (zkCallback-7570-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 1675508 INFO (zkCallback-7559-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: [2]) [junit4] 2> 1675508 INFO (zkCallback-7559-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: [2]) [junit4] 2> 1675508 INFO (zkCallback-7559-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: [2]) [junit4] 2> 1675508 INFO (zkCallback-7543-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: [2]) [junit4] 2> 1675508 INFO (zkCallback-7543-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: [2]) [junit4] 2> 1675508 INFO (zkCallback-7543-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: [2]) [junit4] 2> 1675568 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4673cda3{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0} [junit4] 2> 1675568 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4b086f03{/solr,null,UNAVAILABLE} [junit4] 2> 1675568 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.session node0 Stopped scavenging [junit4] 2> 1675571 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 61304 [junit4] 2> 1675571 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=61304) [junit4] 2> 1675576 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 1675576 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 61304 try number 1 ... [junit4] 2> 1675576 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 11.0.4+11 [junit4] 2> 1675577 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 1675577 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 1675577 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 1675577 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6638d17{/solr,null,AVAILABLE} [junit4] 2> 1675584 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4fe75a17{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:61304} [junit4] 2> 1675584 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.s.Server Started @1675614ms [junit4] 2> 1675584 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61304} [junit4] 2> 1675584 ERROR (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1675588 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 1675588 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0 [junit4] 2> 1675588 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 1675588 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 1675588 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-12-03T21:34:10.766769100Z [junit4] 2> 1675591 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1675594 INFO (zkConnectionManagerCallback-7580-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1675595 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1675595 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 1675850 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 1675854 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7be47751[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1675854 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7be47751[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1675859 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6d83d15b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1675859 WARN (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6d83d15b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 1675862 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61288/solr [junit4] 2> 1675863 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1675874 INFO (zkConnectionManagerCallback-7587-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 1675874 INFO (TEST-BasicAuthIntegrationTest.testBasicAuth-seed#[A1B34DBB130743CC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1675987 INF [...truncated too long message...] ---- [ecj-lint] ---------- [ecj-lint] 15. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\admin\SegmentsInfoRequestHandler.java (at line 243) [ecj-lint] leafReader = ((FilterLeafReader)leafReader).getDelegate(); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'leafReader' is not closed at this location [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 16. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\component\HttpShardHandler.java (at line 149) [ecj-lint] return new JavaBinCodec(null, stringCache).setReadStringAsCharSeq(true); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 17. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\component\HttpShardHandlerFactory.java (at line 415) [ecj-lint] ZkController zkController = core == null ? null : core.getCoreContainer().getZkController(); [ecj-lint] ^^^^^^^^^^^^ [ecj-lint] Resource leak: 'zkController' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 18. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\component\RealTimeGetComponent.java (at line 1118) [ecj-lint] PeerSyncWithLeader peerSync = new PeerSyncWithLeader(rb.req.getCore(), syncWithLeader, nVersions); [ecj-lint] ^^^^^^^^ [ecj-lint] Resource leak: 'peerSync' is never closed [ecj-lint] ---------- [ecj-lint] 19. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\component\RealTimeGetComponent.java (at line 1140) [ecj-lint] PeerSync peerSync = new PeerSync(rb.req.getCore(), replicas, nVersions, cantReachIsSuccess); [ecj-lint] ^^^^^^^^ [ecj-lint] Resource leak: 'peerSync' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 20. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\loader\JavabinLoader.java (at line 137) [ecj-lint] new JavaBinCodec() { [ecj-lint] SolrParams params; [ecj-lint] AddUpdateCommand addCmd = null; [ecj-lint] [ecj-lint] @Override [ecj-lint] public List<Object> readIterator(DataInputInputStream fis) throws IOException { [ecj-lint] while (true) { [ecj-lint] Object o = readVal(fis); [ecj-lint] if (o == END_OBJ) break; [ecj-lint] if (o instanceof NamedList) { [ecj-lint] params = ((NamedList) o).toSolrParams(); [ecj-lint] } else { [ecj-lint] try { [ecj-lint] if (o instanceof byte[]) { [ecj-lint] if (params != null) req.setParams(params); [ecj-lint] byte[] buf = (byte[]) o; [ecj-lint] contentStreamLoader.load(req, rsp, new ContentStreamBase.ByteArrayStream(buf, null), processor); [ecj-lint] } else { [ecj-lint] throw new RuntimeException("unsupported type "); [ecj-lint] } [ecj-lint] } catch (Exception e) { [ecj-lint] throw new RuntimeException(e); [ecj-lint] } finally { [ecj-lint] params = null; [ecj-lint] req.setParams(old); [ecj-lint] } [ecj-lint] } [ecj-lint] } [ecj-lint] return Collections.emptyList(); [ecj-lint] } [ecj-lint] [ecj-lint] }.unmarshal(in); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 21. INFO in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\logging\log4j2\Log4j2Watcher.java (at line 187) [ecj-lint] if (logger == root || root.equals(logger) || isRootLogger(name) || "".equals(name)) { [ecj-lint] ^^^^^^ [ecj-lint] Unlikely argument type for equals(): Map.Entry<String,LoggerConfig> seems to be unrelated to Logger [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 22. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\search\CacheConfig.java (at line 35) [ecj-lint] import org.apache.solr.pkg.PackageLoader; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.pkg.PackageLoader is never used [ecj-lint] ---------- [ecj-lint] 23. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\search\CacheConfig.java (at line 36) [ecj-lint] import org.apache.solr.pkg.PackagePluginHolder; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.pkg.PackagePluginHolder is never used [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 24. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\update\SolrIndexSplitter.java (at line 258) [ecj-lint] HardlinkCopyDirectoryWrapper hardLinkedDir = new HardlinkCopyDirectoryWrapper(splitDir); [ecj-lint] ^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'hardLinkedDir' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 25. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\util\FileUtils.java (at line 52) [ecj-lint] try (FileChannel in = new FileInputStream(src).getChannel(); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] 26. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\util\FileUtils.java (at line 53) [ecj-lint] FileChannel out = new FileOutputStream(destination).getChannel()) { [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 27. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\util\SolrCLI.java (at line 1142) [ecj-lint] SnapshotCloudManager snapshotCloudManager = new SnapshotCloudManager(simCloudManager, config); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'snapshotCloudManager' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 28. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\util\TestInjection.java (at line 265) [ecj-lint] timers.remove(this); [ecj-lint] ^^^^ [ecj-lint] Unlikely argument type new TimerTask(){} for remove(Object) on a Collection<Timer> [ecj-lint] ---------- [ecj-lint] 28 problems (2 errors, 25 warnings, 1 info) BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\build.xml:634: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\build.xml:101: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build.xml:645: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\lucene\common-build.xml:2027: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\lucene\common-build.xml:2066: Compile failed; see the compiler error output for details. Total time: 144 minutes 27 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any 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
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
