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 17:57:53 UTC
[JENKINS] Lucene-Solr-SOLR-13661-Windows (64bit/jdk-11.0.4) - Build
# 46 - Still Failing!
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