You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/11/06 04:38:14 UTC
[JENKINS] Lucene-Solr-SOLR-13661-Windows (64bit/jdk-11.0.4) - Build
# 45 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-SOLR-13661-Windows/45/
Java: 64bit/jdk-11.0.4 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud
Error Message:
IOException occurred when talking to server at: https://127.0.0.1:50298/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:50298/solr
at __randomizedtesting.SeedInfo.seed([5337F2B3E97272F0:823000364D7DF9C2]: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
Build Log:
[...truncated 14534 lines...]
[junit4] Suite: org.apache.solr.cloud.LegacyCloudClusterPropTest
[junit4] 2> 2672424 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\data-dir-266-001
[junit4] 2> 2672424 WARN (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 2672424 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 2672425 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2672426 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2672426 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001
[junit4] 2> 2672426 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2672427 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2672427 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2672528 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:50285
[junit4] 2> 2672528 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50285
[junit4] 2> 2672528 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50285
[junit4] 2> 2672528 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50285
[junit4] 2> 2672529 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2672534 INFO (zkConnectionManagerCallback-15666-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2672535 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2672538 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2672541 INFO (zkConnectionManagerCallback-15668-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2672541 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2672541 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2672544 INFO (zkConnectionManagerCallback-15670-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2672544 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2672651 WARN (jetty-launcher-15671-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2672652 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2672652 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2672652 INFO (jetty-launcher-15671-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> 2672652 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2672652 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2672652 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2672653 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@683441f8{/solr,null,AVAILABLE}
[junit4] 2> 2672654 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@331118b9{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:50298}
[junit4] 2> 2672654 INFO (jetty-launcher-15671-thread-1) [ ] o.e.j.s.Server Started @2672701ms
[junit4] 2> 2672654 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50298}
[junit4] 2> 2672654 ERROR (jetty-launcher-15671-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2672654 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2672655 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2672655 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2672655 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2672655 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T03:26:22.584244800Z
[junit4] 2> 2672656 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2672659 INFO (zkConnectionManagerCallback-15673-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2672659 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2672659 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2672898 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2672899 WARN (jetty-launcher-15671-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2e18c330[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2672899 WARN (jetty-launcher-15671-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2e18c330[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2672902 WARN (jetty-launcher-15671-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4271182c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2672902 WARN (jetty-launcher-15671-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4271182c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2672903 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50285/solr
[junit4] 2> 2672904 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2672906 INFO (zkConnectionManagerCallback-15680-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2672906 INFO (jetty-launcher-15671-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2673009 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2673012 INFO (zkConnectionManagerCallback-15682-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2673012 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2673120 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50298_solr
[junit4] 2> 2673121 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Overseer (id=72205222893191173-127.0.0.1:50298_solr-n_0000000000) starting
[junit4] 2> 2673126 INFO (OverseerStateUpdate-72205222893191173-127.0.0.1:50298_solr-n_0000000000) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50298_solr
[junit4] 2> 2673126 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50298_solr
[junit4] 2> 2673128 INFO (OverseerStateUpdate-72205222893191173-127.0.0.1:50298_solr-n_0000000000) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2673152 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2673167 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2673175 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2673176 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2673177 INFO (jetty-launcher-15671-thread-1) [n:127.0.0.1:50298_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\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\.
[junit4] 2> 2673193 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 2673193 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2673196 INFO (zkConnectionManagerCallback-15691-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2673197 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2673198 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2673199 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50285/solr ready
[junit4] 2> 2673212 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSwitchLegacyCloud
[junit4] 2> 2673224 INFO (qtp971600841-39517) [n:127.0.0.1:50298_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2673226 INFO (qtp971600841-39517) [n:127.0.0.1:50298_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 2673227 INFO (qtp971600841-39519) [n:127.0.0.1:50298_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2673230 INFO (OverseerThreadFactory-11456-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection legacyTrue
[junit4] 2> 2673346 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2673346 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2674364 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2674370 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2674372 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2674372 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 2674372 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2674373 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\legacyTrue_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 2674443 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2674443 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2674446 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2674446 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2674446 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6586667b[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 2674449 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2674449 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2674449 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2674450 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1649421356240994304
[junit4] 2> 2674452 INFO (searcherExecutor-11461-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@6586667b[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2674456 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/legacyTrue/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2674456 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/legacyTrue/leaders/shard1
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 2674460 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/legacyTrue/leaders/shard1/leader after winning as /collections/legacyTrue/leader_elect/shard1/election/72205222893191173-core_node2-n_0000000000
[junit4] 2> 2674462 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 2674566 INFO (zkCallback-15681-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2674566 INFO (zkCallback-15681-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2674567 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2674569 INFO (qtp971600841-39515) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1224
[junit4] 2> 2674572 INFO (qtp971600841-39519) [n:127.0.0.1:50298_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> 2674671 INFO (zkCallback-15681-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2674671 INFO (zkCallback-15681-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2674671 INFO (zkCallback-15681-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2674671 INFO (qtp971600841-39519) [n:127.0.0.1:50298_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1444
[junit4] 2> 2674671 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: legacyTrue
[junit4] 2> 2674684 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=51811462
[junit4] 2> 2674684 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50298_solr
[junit4] 2> 2674685 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 2674685 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50298_solr as DOWN
[junit4] 2> 2674687 INFO (coreCloseExecutor-11466-thread-1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@af14931
[junit4] 2> 2674687 INFO (coreCloseExecutor-11466-thread-1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=SolrCore@af14931
[junit4] 2> 2674687 INFO (coreCloseExecutor-11466-thread-1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7af55d3b: rootName = solr_50298, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@30b8f7d7
[junit4] 2> 2674694 INFO (coreCloseExecutor-11466-thread-1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=SolrCore@af14931
[junit4] 2> 2674695 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2674695 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@405b51c5: rootName = solr_50298, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@382db25d
[junit4] 2> 2674699 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2674699 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52219063: rootName = solr_50298, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5840e3eb
[junit4] 2> 2674701 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2674701 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5a7eb329: rootName = solr_50298, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5c257186
[junit4] 2> 2674701 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2674703 INFO (closeThreadPool-15696-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72205222893191173-127.0.0.1:50298_solr-n_0000000000) closing
[junit4] 2> 2674704 INFO (OverseerStateUpdate-72205222893191173-127.0.0.1:50298_solr-n_0000000000) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50298_solr
[junit4] 2> 2674705 INFO (OverseerAutoScalingTriggerThread-72205222893191173-127.0.0.1:50298_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2674705 INFO (zkCallback-15690-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 2674705 INFO (closeThreadPool-15696-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72205222893191173-127.0.0.1:50298_solr-n_0000000000) closing
[junit4] 2> 2674807 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.Overseer Overseer (id=72205222893191173-127.0.0.1:50298_solr-n_0000000000) closing
[junit4] 2> 2674808 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@331118b9{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 2674808 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@683441f8{/solr,null,UNAVAILABLE}
[junit4] 2> 2674809 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2674810 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForJettyToStop: 50298
[junit4] 2> 2674810 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=50298)
[junit4] 2> 2674811 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 2674811 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 50298 try number 1 ...
[junit4] 2> 2674812 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] 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> 2674812 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2674812 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2674812 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2674813 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3be2eec{/solr,null,AVAILABLE}
[junit4] 2> 2674814 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3680744c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:50298}
[junit4] 2> 2674814 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.s.Server Started @2674861ms
[junit4] 2> 2674814 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=50298}
[junit4] 2> 2674815 ERROR (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T03:26:24.744403300Z
[junit4] 2> 2674815 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2674817 INFO (zkConnectionManagerCallback-15698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2674818 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2674819 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2674951 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2674954 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7b27662[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2674954 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7b27662[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2674957 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43779797[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2674957 WARN (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43779797[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2674958 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50285/solr
[junit4] 2> 2674959 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2674963 INFO (zkConnectionManagerCallback-15705-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2674963 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2675066 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2675069 INFO (zkConnectionManagerCallback-15707-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2675069 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2675078 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50298_solr
[junit4] 2> 2675079 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Overseer (id=72205222893191177-127.0.0.1:50298_solr-n_0000000001) starting
[junit4] 2> 2675084 INFO (OverseerStateUpdate-72205222893191177-127.0.0.1:50298_solr-n_0000000001) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50298_solr
[junit4] 2> 2675084 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50298_solr
[junit4] 2> 2675085 INFO (OverseerStateUpdate-72205222893191177-127.0.0.1:50298_solr-n_0000000001) [n:127.0.0.1:50298_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2675085 INFO (zkCallback-15690-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2675109 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2675126 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2675132 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2675132 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2675135 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\.
[junit4] 2> 2675135 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [n:127.0.0.1:50298_solr ] o.a.s.c.CorePropertiesLocator Cores are: [legacyTrue_shard1_replica_n1]
[junit4] 2> 2675137 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2675144 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2675162 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2675164 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2675176 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2675183 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2675184 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2675185 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 2675185 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_50298.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e22ea5b
[junit4] 2> 2675185 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\legacyTrue_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001\tempDir-001\node1\.\legacyTrue_shard1_replica_n1\data\]
[junit4] 2> 2675233 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2675233 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2675234 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2675234 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2675234 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@98add86[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 2675237 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 2675237 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 2675237 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2675237 INFO (coreLoadExecutor-11480-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1649421357066223616
[junit4] 2> 2675239 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 2675239 INFO (searcherExecutor-11481-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@98add86[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2675243 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/legacyTrue/leaders/shard1
[junit4] 2> 2675244 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2675244 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2675244 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 2675244 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2675245 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 2675245 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/legacyTrue/leaders/shard1/leader after winning as /collections/legacyTrue/leader_elect/shard1/election/72205222893191177-core_node2-n_0000000001
[junit4] 2> 2675246 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:50298/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 2675248 INFO (coreZkRegister-11468-thread-1-processing-n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 c:legacyTrue s:shard1) [n:127.0.0.1:50298_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2675352 INFO (zkCallback-15706-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2675352 INFO (zkCallback-15706-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2675353 INFO (zkCallback-15706-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2676242 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2676263 INFO (qtp2090004114-39580) [n:127.0.0.1:50298_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params name=legacyTrue&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2676271 INFO (OverseerThreadFactory-11476-thread-1-processing-n:127.0.0.1:50298_solr) [n:127.0.0.1:50298_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true&deleteMetricsHistory=true, asyncId=null
[junit4] 2> 2676285 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=null
[junit4] 2> 2676285 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2060187e: rootName = solr_50298, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@7b8b9024
[junit4] 2> 2676299 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@54b9d970
[junit4] 2> 2676299 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=SolrCore@54b9d970
[junit4] 2> 2676299 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=SolrCore@54b9d970
[junit4] 2> 2676305 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/legacyTrue/terms/shard1 to Terms{values={}, version=1}
[junit4] 2> 2676308 INFO (qtp2090004114-39575) [n:127.0.0.1:50298_solr x:legacyTrue_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&deleteMetricsHistory=true&core=legacyTrue_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2} status=0 QTime=23
[junit4] 2> 2676415 INFO (zkCallback-15706-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2676415 INFO (zkCallback-15706-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2676415 INFO (zkCallback-15706-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2676424 INFO (qtp2090004114-39580) [n:127.0.0.1:50298_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=legacyTrue&action=DELETE&wt=javabin&version=2} status=0 QTime=160
[junit4] 2> 2676426 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[5337F2B3E97272F0]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSwitchLegacyCloud
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LegacyCloudClusterPropTest -Dtests.method=testCreateCollectionSwitchLegacyCloud -Dtests.seed=5337F2B3E97272F0 -Dtests.slow=true -Dtests.locale=en-PW -Dtests.timezone=America/Halifax -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] ERROR 3.22s J0 | LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: IOException occurred when talking to server at: https://127.0.0.1:50298/solr
[junit4] > at __randomizedtesting.SeedInfo.seed([5337F2B3E97272F0:823000364D7DF9C2]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:679)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1127)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:896)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:828)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:87)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:79)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] > Caused by: javax.net.ssl.SSLException: Software caused connection abort: recv failed
[junit4] > at java.base/sun.security.ssl.Alert.createSSLException(Alert.java:127)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:320)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:263)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:258)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.handleException(SSLSocketImpl.java:1315)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:839)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:564)
[junit4] > ... 49 more
[junit4] > Suppressed: java.net.SocketException: Software caused connection abort: socket write error
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite0(Native Method)
[junit4] > at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:110)
[junit4] > at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:150)
[junit4] > at java.base/sun.security.ssl.SSLSocketOutputRecord.encodeAlert(SSLSocketOutputRecord.java:81)
[junit4] > at java.base/sun.security.ssl.TransportContext.fatal(TransportContext.java:351)
[junit4] > ... 71 more
[junit4] > Caused by: java.net.SocketException: Software caused connection abort: recv failed
[junit4] > at java.base/java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.base/java.net.SocketInputStream.socketRead(SocketInputStream.java:115)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:168)
[junit4] > at java.base/java.net.SocketInputStream.read(SocketInputStream.java:140)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:448)
[junit4] > at java.base/sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1104)
[junit4] > at java.base/sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:823)
[junit4] > ... 67 more
[junit4] 2> 2676531 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=728019612
[junit4] 2> 2676531 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:50298_solr
[junit4] 2> 2676531 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
[junit4] 2> 2676531 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50298_solr as DOWN
[junit4] 2> 2676537 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2676537 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53fa5cb4: rootName = solr_50298, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@330831cb
[junit4] 2> 2676540 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2676541 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60033c0b: rootName = solr_50298, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5840e3eb
[junit4] 2> 2676541 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2676541 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5fe1dd0a: rootName = solr_50298, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@5c257186
[junit4] 2> 2676541 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2676546 INFO (closeThreadPool-15719-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72205222893191177-127.0.0.1:50298_solr-n_0000000001) closing
[junit4] 2> 2676547 INFO (OverseerStateUpdate-72205222893191177-127.0.0.1:50298_solr-n_0000000001) [n:127.0.0.1:50298_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50298_solr
[junit4] 2> 2676548 INFO (OverseerAutoScalingTriggerThread-72205222893191177-127.0.0.1:50298_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 2676549 INFO (closeThreadPool-15719-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72205222893191177-127.0.0.1:50298_solr-n_0000000001) closing
[junit4] 2> 2676651 INFO (jetty-closer-15715-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72205222893191177-127.0.0.1:50298_solr-n_0000000001) closing
[junit4] 2> 2676651 INFO (jetty-closer-15715-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3680744c{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:50298}
[junit4] 2> 2676651 INFO (jetty-closer-15715-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3be2eec{/solr,null,UNAVAILABLE}
[junit4] 2> 2676651 INFO (jetty-closer-15715-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2676656 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2676873 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 3 /solr/collections/legacyTrue/terms/shard1
[junit4] 2> 3 /solr/collections/legacyTrue/state.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 15 /solr/collections/legacyTrue/state.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/overseer/queue
[junit4] 2> 9 /solr/collections
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 8 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/autoscaling/events/.scheduled_maintenance
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 2676878 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:50285
[junit4] 2> 2676878 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50285
[junit4] 2> 2676878 INFO (SUITE-LegacyCloudClusterPropTest-seed#[5337F2B3E97272F0]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50285
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.LegacyCloudClusterPropTest_5337F2B3E97272F0-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=2025, maxMBSortInHeap=5.01384114256044, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@fbe9238), locale=en-PW, timezone=America/Halifax
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 11.0.4 (64-bit)/cpus=3,threads=1,free=174504512,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [SuggesterWFSTTest, TestZkAclsWithHadoopAuth, MetricsHistoryWithAuthIntegrationTest, TestValueSourceCache, TestPayloadScoreQParserPlugin, CreateCollectionCleanupTest, TestSchemaManager, TestCloudRecovery2, ScheduledMaintenanceTriggerTest, TestConfigSetsAPIExclusivity, TestSimComputePlanAction, DocExpirationUpdateProcessorFactoryTest, OverseerTaskQueueTest, ZkStateReaderTest, TestFiltering, SegmentsInfoRequestHandlerTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestSimLargeCluster, TestSimScenario, HdfsTlogReplayBufferedWhileIndexingTest, HdfsNNFailoverTest, CollectionPropsTest, NodeAddedTriggerIntegrationTest, DistributedQueryComponentCustomSortTest, TestSchemaResource, HdfsBasicDistributedZk2Test, MoreLikeThisHandlerTest, TestSurroundQueryParser, TestMacroExpander, TestSolrFieldCacheBean, CheckHdfsIndexTest, RestartWhileUpdatingTest, DeleteInactiveReplicaTest, TestBinaryResponseWriter, HdfsWriteToMultipleCollectionsTest, TestRandomFaceting, HdfsCollectionsAPIDistributedZkTest, TestDistributedSearch, StatsReloadRaceTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, DistributedSuggestComponentTest, PeerSyncWithBufferUpdatesTest, TestClassicSimilarityFactory, GraphQueryTest, DistributedFacetPivotWhiteBoxTest, RangeFacetCloudTest, TestSubQueryTransformerCrossCore, SignificantTermsQParserPluginTest, TestCloudManagedSchema, CSVRequestHandlerTest, DistributedQueryElevationComponentTest, DistributedVersionInfoTest, ScheduledTriggerTest, QueryElevationComponentTest, TestSimpleTextCodec, TestCloudJSONFacetJoinDomain, TestHttpShardHandlerFactory, TestNumericTerms64, HdfsSyncSliceTest, SimplePostToolTest, DocValuesMissingTest, TestComplexPhraseLeadingWildcard, TestDistribPackageStore, TestCloudPhrasesIdentificationComponent, TestStandardQParsers, HighlighterConfigTest, FieldAnalysisRequestHandlerTest, SolrCmdDistributorTest, ChaosMonkeySafeLeaderWithPullReplicasTest, RankQueryTest, TestLuceneMatchVersion, TestUnInvertedFieldException, TestLegacyBM25SimilarityFactory, TestUseDocValuesAsStored2, ShardsWhitelistTest, RecoveryZkTest, TestCloudDeleteByQuery, TestSolrCLIRunExample, CopyFieldTest, AliasIntegrationTest, TestUnifiedSolrHighlighter, TestMinMaxOnMultiValuedField, LeaderFailoverAfterPartitionTest, TimeZoneUtilsTest, CustomHighlightComponentTest, TestConfigSetImmutable, TestWithCollection, TestTrackingShardHandlerFactory, TestRealTimeGet, SuggestComponentTest, DistribCursorPagingTest, MetricsHandlerTest, StatsComponentTest, SolrIndexMetricsTest, TestTestInjection, TestDocSet, TestFieldCache, TestManagedSynonymFilterFactory, TestReloadAndDeleteDocs, TestFieldCacheSort, BlockDirectoryTest, TestPKIAuthenticationPlugin, TestCaffeineCache, AnalyticsMergeStrategyTest, SimpleMLTQParserTest, TestWordDelimiterFilterFactory, TestQuerySenderListener, TestHdfsBackupRestoreCore, TestExactSharedStatsCache, DistribJoinFromCollectionTest, XsltUpdateRequestHandlerTest, TemplateUpdateProcessorTest, CdcrUpdateLogTest, TestRangeQuery, MetricTriggerIntegrationTest, URLClassifyProcessorTest, TestXmlQParserPlugin, BigEndianAscendingWordSerializerTest, TestSmileRequest, MissingSegmentRecoveryTest, RoutingToNodesWithPropertiesTest, BlobRepositoryCloudTest, NotRequiredUniqueKeyTest, SolrLogAuditLoggerPluginTest, TestJoin, TestIndexSearcher, AuditLoggerPluginTest, HLLSerializationTest, TestJettySolrRunner, TimeRoutedAliasUpdateProcessorTest, TestMaxTokenLenTokenizer, DirectUpdateHandlerTest, TestBadConfig, JvmMetricsTest, AtomicUpdateRemovalJavabinTest, SolrJmxReporterTest, PrimitiveFieldTypeTest, TestCopyFieldCollectionResource, TestStressRecovery, TestConfigSets, SolrRequestParserTest, TestUniqueKeyFieldResource, ExternalFileFieldSortTest, FullSolrCloudDistribCmdsTest, TestPullReplicaErrorHandling, TestConfigsApi, HdfsDirectoryFactoryTest, TestSortableTextField, TestInPlaceUpdatesDistrib, UninvertDocValuesMergePolicyTest, DimensionalRoutedAliasUpdateProcessorTest, TestDynamicURP, TestCustomStream, OutOfBoxZkACLAndCredentialsProvidersTest, ConvertedLegacyTest, DeleteShardTest, ImplicitSnitchTest, DateFieldTest, LeaderElectionIntegrationTest, SolrGraphiteReporterTest, TestDistribDocBasedVersion, ZkCLITest, SolrMetricReporterTest, TestLMDirichletSimilarityFactory, ZkFailoverTest, SolrInfoBeanTest, TriggerSetPropertiesIntegrationTest, CurrencyRangeFacetCloudTest, RandomizedTaggerTest, TestDownShardTolerantSearch, CollectionReloadTest, TestSolrIndexConfig, TestFieldResource, TestStressLiveNodes, TestSimExtremeIndexing, DisMaxRequestHandlerTest, TestHdfsCloudBackupRestore, MoveReplicaHDFSTest, RequestHandlersTest, XmlInterpolationTest, AddBlockUpdateTest, CoreAdminHandlerTest, CdcrOpsAndBoundariesTest, OrderedExecutorTest, ConcurrentDeleteAndCreateCollectionTest, SpellingQueryConverterTest, CircularListTest, RemoteQueryErrorTest, TestRecoveryHdfs, SortByFunctionTest, DirectSolrSpellCheckerTest, TestMultiWordSynonyms, AnalysisAfterCoreReloadTest, CollectionsAPISolrJTest, CoreAdminRequestStatusTest, TestSQLHandler, MultiSolrCloudTestCaseTest, TransactionLogTest, ClassificationUpdateProcessorIntegrationTest, TestOverriddenPrefixQueryForCustomFieldType, TestEmbeddedSolrServerConstructors, TestDynamicLoading, TestHalfAndHalfDocValues, TestInfoStreamLogging, NoCacheHeaderTest, BasicAuthOnSingleNodeTest, DirectoryFactoryTest, TestReloadDeadlock, TestMergePolicyConfig, QueryEqualityTest, TestExtendedDismaxParser, TestUtilizeNode, TestRemoteStreaming, TestSolr4Spatial2, TestLegacyTerms, JavabinLoaderTest, TestClusterStateMutator, JavaBinAtomicUpdateMultivalueTest, TestCloudPseudoReturnFields, BasicFunctionalityTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestDistributedMissingSort, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping, TestRandomDVFaceting, TestSolrCoreProperties, TestTolerantSearch, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, TestCharFilters, TestDeprecatedFilters, TestReversedWildcardFilterFactory, TokenizerChainTest, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, ConnectionReuseTest, ActionThrottleTest, AddReplicaTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, BasicZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, HttpPartitionOnCommitTest, HttpPartitionTest, LeaderTragicEventTest, LegacyCloudClusterPropTest]
[junit4] Completed [492/887 (1!)] on J0 in 6.53s, 1 test, 1 error <<< FAILURES!
[...truncated 49918 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj113085597
[ecj-lint] Compiling 1298 source files to C:\Users\jenkins\AppData\Local\Temp\ecj113085597
[ecj-lint] Processing annotations
[ecj-lint] Annotations processed
[ecj-lint] Processing annotations
[ecj-lint] No elements to process
[ecj-lint] invalid Class-Path header in manifest of jar file: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\lib\org.restlet-2.3.0.jar
[ecj-lint] invalid Class-Path header in manifest of jar file: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\lib\org.restlet.ext.servlet-2.3.0.jar
[ecj-lint] ----------
[ecj-lint] 1. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\client\solrj\embedded\EmbeddedSolrServer.java (at line 255)
[ecj-lint] return (NamedList<Object>) new JavaBinCodec(resolver).unmarshal(in);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 2. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\RecoveryStrategy.java (at line 232)
[ecj-lint] ReplicationHandler replicationHandler = (ReplicationHandler) handler;
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'replicationHandler' is never closed
[ecj-lint] ----------
[ecj-lint] 3. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\RecoveryStrategy.java (at line 628)
[ecj-lint] PeerSyncWithLeader peerSyncWithLeader = new PeerSyncWithLeader(core,
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'peerSyncWithLeader' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 4. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\SyncStrategy.java (at line 186)
[ecj-lint] PeerSync peerSync = new PeerSync(core, syncWith, core.getUpdateHandler().getUpdateLog().getNumRecordsToKeep(), true, peerSyncOnlyWithActive, false);
[ecj-lint] ^^^^^^^^
[ecj-lint] Resource leak: 'peerSync' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 5. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimCloudManager.java (at line 794)
[ecj-lint] throw new UnsupportedOperationException("must add at least 1 node first");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'queryRequest' is not closed at this location
[ecj-lint] ----------
[ecj-lint] 6. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimCloudManager.java (at line 800)
[ecj-lint] throw new UnsupportedOperationException("must add at least 1 node first");
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'queryRequest' is not closed at this location
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 7. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimScenario.java (at line 406)
[ecj-lint] SnapshotCloudManager snapshotCloudManager = new SnapshotCloudManager(scenario.cluster, null);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'snapshotCloudManager' is never closed
[ecj-lint] ----------
[ecj-lint] 8. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimScenario.java (at line 739)
[ecj-lint] throw new IOException("currently only one listener can be set per trigger. Trigger name: " + trigger);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'listener' is not closed at this location
[ecj-lint] ----------
[ecj-lint] 9. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimScenario.java (at line 929)
[ecj-lint] SnapshotCloudManager snapshotCloudManager = new SnapshotCloudManager(scenario.cluster, null);
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'snapshotCloudManager' is never closed
[ecj-lint] ----------
[ecj-lint] 10. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\cloud\autoscaling\sim\SimScenario.java (at line 968)
[ecj-lint] SimScenario scenario = new SimScenario();
[ecj-lint] ^^^^^^^^
[ecj-lint] Resource leak: 'scenario' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 11. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\core\CoreContainer.java (at line 740)
[ecj-lint] SolrFieldCacheBean fieldCacheBean = new SolrFieldCacheBean();
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'fieldCacheBean' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 12. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\CdcrRequestHandler.java (at line 741)
[ecj-lint] ReplicationHandler replicationHandler = (ReplicationHandler) handler;
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'replicationHandler' is never closed
[ecj-lint] ----------
[ecj-lint] 13. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\CdcrRequestHandler.java (at line 760)
[ecj-lint] ReplicationHandler replicationHandler = (ReplicationHandler) handler;
[ecj-lint] ^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: 'replicationHandler' is never closed
[ecj-lint] ----------
[ecj-lint] ----------
[ecj-lint] 14. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\java\org\apache\solr\handler\SolrConfigHandler.java (at line 557)
[ecj-lint] new PluginBag.RuntimeLib(req.getCore()).init(new PluginInfo(info.tag, op.getDataMap()));
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] Resource leak: '<unassigned Closeable value>' is never closed
[ecj-lint] ----------
[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: 155 minutes 30 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
[JENKINS] Lucene-Solr-SOLR-13661-Windows (64bit/jdk-11.0.4) - Build
# 46 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-SOLR-13661-Windows/46/
Java: 64bit/jdk-11.0.4 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.HttpPartitionTest.test
Error Message:
Doc with id=2 not found in http://127.0.0.1:60518/mhk/vj/c8n_1x2_leader_session_loss due to: Path not found: /id; rsp={doc=null}
Stack Trace:
java.lang.AssertionError: Doc with id=2 not found in http://127.0.0.1:60518/mhk/vj/c8n_1x2_leader_session_loss due to: Path not found: /id; rsp={doc=null}
at __randomizedtesting.SeedInfo.seed([C0FC2B01B424F012:48A814DB1AD89DEA]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartitionTest.java:563)
at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(HttpPartitionTest.java:508)
at org.apache.solr.cloud.HttpPartitionTest.testLeaderZkSessionLoss(HttpPartitionTest.java:459)
at org.apache.solr.cloud.HttpPartitionTest.test(HttpPartitionTest.java:148)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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)
Build Log:
[...truncated 13049 lines...]
[junit4] Suite: org.apache.solr.cloud.HttpPartitionTest
[junit4] 2> 310534 INFO (SUITE-HttpPartitionTest-seed#[C0FC2B01B424F012]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 310536 INFO (SUITE-HttpPartitionTest-seed#[C0FC2B01B424F012]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\data-dir-24-001
[junit4] 2> 310536 INFO (SUITE-HttpPartitionTest-seed#[C0FC2B01B424F012]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 310538 INFO (SUITE-HttpPartitionTest-seed#[C0FC2B01B424F012]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-5776")
[junit4] 2> 310538 INFO (SUITE-HttpPartitionTest-seed#[C0FC2B01B424F012]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /mhk/vj
[junit4] 2> 310543 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 310543 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 310543 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 310643 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer start zk server on port:60503
[junit4] 2> 310643 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:60503
[junit4] 2> 310643 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60503
[junit4] 2> 310643 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60503
[junit4] 2> 310646 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 310652 INFO (zkConnectionManagerCallback-1083-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310652 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 310656 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 310659 INFO (zkConnectionManagerCallback-1085-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 310659 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 310661 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 310664 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 310666 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 310668 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 310670 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 310673 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 310676 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 310678 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 310680 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 310683 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 310685 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 310687 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 312173 WARN (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 312173 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 312173 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 312173 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] 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> 312174 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 312174 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 312174 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 312175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3cf6787{/mhk/vj,null,AVAILABLE}
[junit4] 2> 312176 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17f352d9{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60519}
[junit4] 2> 312176 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.s.Server Started @312211ms
[junit4] 2> 312176 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/mhk/vj, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\tempDir-001/control/data, hostPort=60518, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\control-001\cores}
[junit4] 2> 312177 ERROR (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 312177 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 312177 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 312177 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 312177 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 312177 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T16:05:54.499944200Z
[junit4] 2> 312179 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312183 INFO (zkConnectionManagerCallback-1087-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312183 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312287 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 312287 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\control-001\solr.xml
[junit4] 2> 312291 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 312291 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 312292 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 312798 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 312800 WARN (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60aafa7c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312800 WARN (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60aafa7c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312805 WARN (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f66608[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312805 WARN (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f66608[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 312805 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60503/solr
[junit4] 2> 312807 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312809 INFO (zkConnectionManagerCallback-1094-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312809 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312913 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312916 INFO (zkConnectionManagerCallback-1096-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312917 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 313091 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60518_mhk%2Fvj
[junit4] 2> 313092 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.Overseer Overseer (id=72208209415962628-127.0.0.1:60518_mhk%2Fvj-n_0000000000) starting
[junit4] 2> 313101 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60518_mhk%2Fvj
[junit4] 2> 313155 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 313185 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 313194 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 313194 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 313198 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [n:127.0.0.1:60518_mhk%2Fvj ] 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.cloud.HttpPartitionTest_C0FC2B01B424F012-001\control-001\cores
[junit4] 2> 313215 INFO (OverseerStateUpdate-72208209415962628-127.0.0.1:60518_mhk%2Fvj-n_0000000000) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:60518_mhk%2Fvj
[junit4] 2> 313218 INFO (OverseerStateUpdate-72208209415962628-127.0.0.1:60518_mhk%2Fvj-n_0000000000) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 313227 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 313271 INFO (zkConnectionManagerCallback-1105-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313271 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 313273 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 313274 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60503/solr ready
[junit4] 2> 313276 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60564,localport=60518], receiveBufferSize:65536
[junit4] 2> 313276 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60519,localport=60565], receiveBufferSize=65536
[junit4] 2> 313278 INFO (qtp891717114-3214) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:60518_mhk%252Fvj&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 313282 INFO (OverseerThreadFactory-959-thread-1-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 313392 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60569,localport=60518], receiveBufferSize:65536
[junit4] 2> 313392 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60519,localport=60570], receiveBufferSize=65536
[junit4] 2> 313394 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 313395 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 314420 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 314435 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 314512 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 314536 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 314536 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 314536 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 314537 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31225180904642236]
[junit4] 2> 314539 WARN (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 314606 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 314606 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 314607 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 314607 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 314609 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.841796875, floorSegmentMB=0.62109375, forceMergeDeletesPctAllowed=21.527933518138152, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.60521634380382
[junit4] 2> 314609 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@565ae0c9[control_collection_shard1_replica_n1] main]
[junit4] 2> 314612 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 314612 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 314613 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 314613 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1649469142529474560
[junit4] 2> 314617 INFO (searcherExecutor-960-thread-1-processing-n:127.0.0.1:60518_mhk%2Fvj x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@565ae0c9[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 314621 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 314621 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 314629 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 314629 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 314629 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60518/mhk/vj/control_collection_shard1_replica_n1/
[junit4] 2> 314630 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 314630 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:60518/mhk/vj/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 314630 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72208209415962628-core_node2-n_0000000000
[junit4] 2> 314632 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60518/mhk/vj/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 314737 INFO (zkCallback-1095-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 314737 INFO (zkCallback-1095-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 314737 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 314742 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1349
[junit4] 2> 314746 INFO (qtp891717114-3214) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 314840 INFO (zkCallback-1095-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 314840 INFO (zkCallback-1095-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 314840 INFO (zkCallback-1095-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 314841 INFO (qtp891717114-3214) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:60518_mhk%252Fvj&wt=javabin&version=2} status=0 QTime=1563
[junit4] 2> 314842 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 314950 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 314953 INFO (zkConnectionManagerCallback-1111-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 314953 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 314955 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 314956 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60503/solr ready
[junit4] 2> 314956 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 314958 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60576,localport=60518], receiveBufferSize:65536
[junit4] 2> 314958 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60519,localport=60577], receiveBufferSize=65536
[junit4] 2> 314959 INFO (qtp891717114-3215) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 314963 INFO (OverseerThreadFactory-959-thread-2-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 314963 INFO (OverseerCollectionConfigSetProcessor-72208209415962628-127.0.0.1:60518_mhk%2Fvj-n_0000000000) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 315169 WARN (OverseerThreadFactory-959-thread-2-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 315171 INFO (qtp891717114-3215) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 315173 INFO (qtp891717114-3215) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=214
[junit4] 2> 315174 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 315174 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 315175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 315175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 315175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 315175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 315175 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 316994 INFO (OverseerCollectionConfigSetProcessor-72208209415962628-127.0.0.1:60518_mhk%2Fvj-n_0000000000) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 317437 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-1-001 of type NRT
[junit4] 2> 317449 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 317449 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 317449 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 317449 INFO (closeThreadPool-1112-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> 317451 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 317451 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 317451 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 317451 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68946a4c{/mhk/vj,null,AVAILABLE}
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1bb00a67{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60580}
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.Server Started @317507ms
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/mhk/vj, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\tempDir-001/jetty1, hostPort=60579, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-1-001\cores}
[junit4] 2> 317473 ERROR (closeThreadPool-1112-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 317473 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 317474 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 317474 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T16:05:59.796920900Z
[junit4] 2> 317477 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 317515 INFO (zkConnectionManagerCallback-1114-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 317515 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 317620 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 317620 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-1-001\solr.xml
[junit4] 2> 317632 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 317632 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 317632 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 317793 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 317837 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a472f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 317837 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a472f3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 317864 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c340390[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 317864 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c340390[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 317864 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60503/solr
[junit4] 2> 317867 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 317883 INFO (zkConnectionManagerCallback-1121-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 317883 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 317991 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 318005 INFO (zkConnectionManagerCallback-1123-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 318005 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 318014 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 318024 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.ZkController Publish node=127.0.0.1:60579_mhk%2Fvj as DOWN
[junit4] 2> 318027 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 318027 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60579_mhk%2Fvj
[junit4] 2> 318033 INFO (zkCallback-1095-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 318069 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 318091 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 318098 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 318098 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 318101 INFO (closeThreadPool-1112-thread-1) [n:127.0.0.1:60579_mhk%2Fvj ] 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.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-1-001\cores
[junit4] 2> 318130 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:60579_mhk%2Fvj
[junit4] 2> 318163 INFO (zkCallback-1110-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 318172 INFO (zkCallback-1122-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 319692 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001 of type NRT
[junit4] 2> 319694 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 319694 INFO (closeThreadPool-1112-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> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 319694 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d538f1b{/mhk/vj,null,AVAILABLE}
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ccde66c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60597}
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.Server Started @319750ms
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/mhk/vj, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\tempDir-001/jetty2, hostPort=60596, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001\cores, replicaType=NRT}
[junit4] 2> 319715 ERROR (closeThreadPool-1112-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 319715 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T16:06:02.037799800Z
[junit4] 2> 319718 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 319759 INFO (zkConnectionManagerCallback-1129-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 319759 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 319862 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 319862 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001\solr.xml
[junit4] 2> 319869 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 319869 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 319869 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 320014 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 320081 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@10f599de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 320081 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@10f599de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 320103 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3267bac1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 320103 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3267bac1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 320105 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60503/solr
[junit4] 2> 320105 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 320174 INFO (zkConnectionManagerCallback-1136-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 320174 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 320283 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 320325 INFO (zkConnectionManagerCallback-1138-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 320325 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 320337 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 320343 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:60596_mhk%2Fvj as DOWN
[junit4] 2> 320344 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 320344 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60596_mhk%2Fvj
[junit4] 2> 320349 INFO (zkCallback-1095-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 320349 INFO (zkCallback-1122-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 320349 INFO (zkCallback-1110-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 320387 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 320409 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 320414 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 320414 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 320420 INFO (closeThreadPool-1112-thread-1) [ ] 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.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001\cores
[junit4] 2> 320453 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:60596_mhk%2Fvj
[junit4] 2> 320538 INFO (zkCallback-1137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 321930 INFO (TEST-HttpPartitionTest.test-seed#[C0FC2B01B424F012]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001 of type NRT
[junit4] 2> 321930 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 321930 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 321930 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 321930 INFO (closeThreadPool-1112-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> 321935 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 321935 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 321935 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 321935 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72ace492{/mhk/vj,null,AVAILABLE}
[junit4] 2> 321936 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2951f344{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:60614}
[junit4] 2> 321936 INFO (closeThreadPool-1112-thread-1) [ ] o.e.j.s.Server Started @321970ms
[junit4] 2> 321936 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/mhk/vj, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\tempDir-001/jetty3, hostPort=60613, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001\cores, replicaType=NRT}
[junit4] 2> 321936 ERROR (closeThreadPool-1112-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 321936 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 321937 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 321937 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 321937 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 321937 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-11-06T16:06:04.259481400Z
[junit4] 2> 321938 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 321941 INFO (zkConnectionManagerCallback-1144-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 321941 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 322043 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 322043 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001\solr.xml
[junit4] 2> 322050 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 322050 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 322051 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 322138 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 322140 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@636432e3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 322140 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@636432e3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 322143 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6715412c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 322144 WARN (closeThreadPool-1112-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6715412c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 322144 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60503/solr
[junit4] 2> 322146 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 322149 INFO (zkConnectionManagerCallback-1151-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 322149 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 322251 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 322254 INFO (zkConnectionManagerCallback-1153-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 322254 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 322262 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 322267 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:60613_mhk%2Fvj as DOWN
[junit4] 2> 322268 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 322268 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60613_mhk%2Fvj
[junit4] 2> 322270 INFO (zkCallback-1110-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 322270 INFO (zkCallback-1122-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 322270 INFO (zkCallback-1137-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 322270 INFO (zkCallback-1095-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 322272 INFO (zkCallback-1152-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 322298 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 322319 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 322325 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 322325 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 322328 INFO (closeThreadPool-1112-thread-1) [ ] 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.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001\cores
[junit4] 2> 322357 INFO (closeThreadPool-1112-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:60613_mhk%2Fvj
[junit4] 2> 322361 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60631,localport=60518], receiveBufferSize:65536
[junit4] 2> 322361 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60519,localport=60633], receiveBufferSize=65536
[junit4] 2> 322361 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:60613_mhk%252Fvj&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 322361 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60632,localport=60518], receiveBufferSize:65536
[junit4] 2> 322362 INFO (SocketProxy-Acceptor-60518) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60519,localport=60634], receiveBufferSize=65536
[junit4] 2> 322363 INFO (qtp891717114-3218) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:60579_mhk%252Fvj&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 322363 INFO (qtp891717114-3213) [n:127.0.0.1:60518_mhk%2Fvj ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:60596_mhk%252Fvj&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 322366 INFO (OverseerThreadFactory-959-thread-3-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:60613_mhk%2Fvj for creating new replica of shard shard2 for collection collection1
[junit4] 2> 322368 INFO (OverseerThreadFactory-959-thread-4-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:60596_mhk%2Fvj for creating new replica of shard shard1 for collection collection1
[junit4] 2> 322368 INFO (OverseerThreadFactory-959-thread-3-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 322369 INFO (OverseerThreadFactory-959-thread-4-processing-n:127.0.0.1:60518_mhk%2Fvj) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 322371 INFO (SocketProxy-Acceptor-60613) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60635,localport=60613], receiveBufferSize:65536
[junit4] 2> 322371 INFO (SocketProxy-Acceptor-60613) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60614,localport=60636], receiveBufferSize=65536
[junit4] 2> 322372 INFO (SocketProxy-Acceptor-60596) [ ] o.a.s.c.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=60637,localport=60596], receiveBufferSize:65536
[junit4] 2> 322372 INFO (SocketProxy-Acceptor-60596) [ ] o.a.s.c.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=60597,localport=60638], receiveBufferSize=65536
[junit4] 2> 322381 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj x:collection1_shard2_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 322381 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj x:collection1_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 323407 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 323407 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 323425 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=test
[junit4] 2> 323434 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n2] Schema name=test
[junit4] 2> 323536 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 323577 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 323579 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 323579 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n2' (registry 'solr.core.collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 323579 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001\cores\collection1_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-2-001\cores\collection1_shard1_replica_n2\data\]
[junit4] 2> 323581 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31225180904642236]
[junit4] 2> 323590 WARN (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 323599 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 323600 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n1' (registry 'solr.core.collection1.shard2.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@6de36f72
[junit4] 2> 323600 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001\cores\collection1_shard2_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-SOLR-13661-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.HttpPartitionTest_C0FC2B01B424F012-001\shard-3-001\cores\collection1_shard2_replica_n1\data\]
[junit4] 2> 323601 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=49, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31225180904642236]
[junit4] 2> 323608 WARN (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 323677 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 323677 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 323679 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 323679 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 323681 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.841796875, floorSegmentMB=0.62109375, forceMergeDeletesPctAllowed=21.527933518138152, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.60521634380382
[junit4] 2> 323681 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3b043a4c[collection1_shard1_replica_n2] main]
[junit4] 2> 323684 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 323684 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 323685 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 323685 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1649469152042156032
[junit4] 2> 323689 INFO (searcherExecutor-992-thread-1-processing-n:127.0.0.1:60596_mhk%2Fvj x:collection1_shard1_replica_n2 c:collection1 s:shard1) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SolrCore [collection1_shard1_replica_n2] Registered new searcher Searcher@3b043a4c[collection1_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 323693 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 323693 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60596/mhk/vj/collection1_shard1_replica_n2/
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:60596/mhk/vj/collection1_shard1_replica_n2/ has no replicas
[junit4] 2> 323698 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72208209415962636-core_node4-n_0000000000
[junit4] 2> 323701 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60596/mhk/vj/collection1_shard1_replica_n2/ shard1
[junit4] 2> 323703 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 323703 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 323704 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 323704 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 323707 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.841796875, floorSegmentMB=0.62109375, forceMergeDeletesPctAllowed=21.527933518138152, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=21.60521634380382
[junit4] 2> 323709 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@165479b6[collection1_shard2_replica_n1] main]
[junit4] 2> 323711 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 323711 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 323712 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 323712 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1649469152070467584
[junit4] 2> 323715 INFO (searcherExecutor-993-thread-1-processing-n:127.0.0.1:60613_mhk%2Fvj x:collection1_shard2_replica_n1 c:collection1 s:shard2) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher Searcher@165479b6[collection1_shard2_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 323720 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 323720 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 323725 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 323725 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 323725 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60613/mhk/vj/collection1_shard2_replica_n1/
[junit4] 2> 323725 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 323726 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:60613/mhk/vj/collection1_shard2_replica_n1/ has no replicas
[junit4] 2> 323726 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72208209415962639-core_node3-n_0000000000
[junit4] 2> 323728 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60613/mhk/vj/collection1_shard2_replica_n1/ shard2
[junit4] 2> 323832 INFO (zkCallback-1152-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 323832 INFO (zkCallback-1137-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 323833 INFO (zkCallback-1152-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 323834 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 323834 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 323839 INFO (qtp596198302-3313) [n:127.0.0.1:60596_mhk%2Fvj c:collection1 s:shard1 x:collection1_shard1_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n2&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1458
[junit4] 2> 323839 INFO (qtp956213750-3345) [n:127.0.0.1:60613_mhk%2Fvj c:collection1 s:shard2 x:collection1_shard2_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n1&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1458
[junit4] 2> 323844 INFO (qtp891717114-3216) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:60613_mhk%252Fvj&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1483
[junit4] 2> 323844 INFO (qtp891717114-3213) [n:127.0.0.1:60518_mhk%2Fvj c:collection1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:60596_mhk%252Fvj&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=1481
[junit4] 2> 323939 INFO (zkCallback-1137-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 323939 INFO (zkCallback-1137-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 323939 INFO (zkCallback-1152-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnect
[...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: 153 minutes 32 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