You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/23 21:21:48 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 1035 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1035/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.DeleteReplicaTest.deleteReplicaAndVerifyDirectoryCleanup
Error Message:
No registered leader was found after waiting for 4000ms , collection: deletereplica_test slice: shard1 saw state=DocCollection(deletereplica_test//collections/deletereplica_test/state.json/6)={ "pullReplicas":"0", "replicationFactor":"2", "shards":{"shard1":{ "range":"80000000-7fffffff", "state":"active", "replicas":{"core_node3":{ "core":"deletereplica_test_shard1_replica_n1", "base_url":"http://127.0.0.1:50249/solr", "node_name":"127.0.0.1:50249_solr", "state":"active", "type":"NRT", "force_set_state":"false"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"1", "autoAddReplicas":"false", "nrtReplicas":"2", "tlogReplicas":"0"} with live_nodes=[127.0.0.1:50246_solr, 127.0.0.1:50248_solr, 127.0.0.1:50247_solr, 127.0.0.1:50249_solr]
Stack Trace:
org.apache.solr.common.SolrException: No registered leader was found after waiting for 4000ms , collection: deletereplica_test slice: shard1 saw state=DocCollection(deletereplica_test//collections/deletereplica_test/state.json/6)={
"pullReplicas":"0",
"replicationFactor":"2",
"shards":{"shard1":{
"range":"80000000-7fffffff",
"state":"active",
"replicas":{"core_node3":{
"core":"deletereplica_test_shard1_replica_n1",
"base_url":"http://127.0.0.1:50249/solr",
"node_name":"127.0.0.1:50249_solr",
"state":"active",
"type":"NRT",
"force_set_state":"false"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"1",
"autoAddReplicas":"false",
"nrtReplicas":"2",
"tlogReplicas":"0"} with live_nodes=[127.0.0.1:50246_solr, 127.0.0.1:50248_solr, 127.0.0.1:50247_solr, 127.0.0.1:50249_solr]
at __randomizedtesting.SeedInfo.seed([46F4302BDD6C2A65:3614B4915923836E]:0)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:978)
at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:955)
at org.apache.solr.cloud.DeleteReplicaTest.deleteReplicaAndVerifyDirectoryCleanup(DeleteReplicaTest.java:164)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.DistribCursorPagingTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.DistribCursorPagingTest: 1) Thread[id=14857, name=SessionTracker, state=TIMED_WAITING, group=TGRP-DistribCursorPagingTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.DistribCursorPagingTest:
1) Thread[id=14857, name=SessionTracker, state=TIMED_WAITING, group=TGRP-DistribCursorPagingTest]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([46F4302BDD6C2A65]:0)
Build Log:
[...truncated 15012 lines...]
[junit4] Suite: org.apache.solr.cloud.DistribCursorPagingTest
[junit4] 2> 1278889 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1278890 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/data-dir-153-001
[junit4] 2> 1278890 WARN (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12
[junit4] 2> 1278890 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1278898 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-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-9182 - causes OOM) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1278899 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1278900 INFO (SUITE-DistribCursorPagingTest-seed#[46F4302BDD6C2A65]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1278910 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1278924 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1278924 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1279028 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer start zk server on port: 50078
[junit4] 2> 1279029 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:50078
[junit4] 2> 1279029 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50078
[junit4] 2> 1279029 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50078
[junit4] 2> 1279054 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1279077 INFO (zkConnectionManagerCallback-9869-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1279078 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1279096 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1279107 INFO (zkConnectionManagerCallback-9871-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1279107 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1279120 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-deeppaging.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1279125 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema-sorts.xml to /configs/conf1/schema.xml
[junit4] 2> 1279147 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1279153 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1279181 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1279202 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1279222 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1279226 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1279237 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1279330 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1279340 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1279348 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1281017 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1281017 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1281017 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1281017 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1281032 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1281032 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1281032 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1281036 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63e58584{/,null,AVAILABLE}
[junit4] 2> 1281040 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@20d82e3f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50090}
[junit4] 2> 1281040 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.s.Server Started @1281129ms
[junit4] 2> 1281040 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/tempDir-001/control/data, replicaType=NRT, hostContext=/, hostPort=50090, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/control-001/cores}
[junit4] 2> 1281042 ERROR (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1281042 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1281042 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1281042 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1281042 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1281042 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T15:35:49.052Z
[junit4] 2> 1281047 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1281057 INFO (zkConnectionManagerCallback-9873-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1281060 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1281171 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1281171 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/control-001/solr.xml
[junit4] 2> 1281183 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1281183 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1281185 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1281276 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1281277 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42b9590b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1281277 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42b9590b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1281286 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7065d9b8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1281286 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7065d9b8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1281287 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50078/solr
[junit4] 2> 1281291 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1281293 INFO (zkConnectionManagerCallback-9884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1281294 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1281405 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1281408 INFO (zkConnectionManagerCallback-9886-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1281408 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1281684 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50090_
[junit4] 2> 1281686 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.Overseer Overseer (id=72091869411475460-127.0.0.1:50090_-n_0000000000) starting
[junit4] 2> 1281701 INFO (OverseerStateUpdate-72091869411475460-127.0.0.1:50090_-n_0000000000) [n:127.0.0.1:50090_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50090_
[junit4] 2> 1281702 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50090_
[junit4] 2> 1281708 INFO (zkCallback-9885-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1281717 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1281717 WARN (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1281843 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1281880 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1281890 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1281891 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1281893 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [n:127.0.0.1:50090_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/control-001/cores
[junit4] 2> 1281926 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1281928 INFO (zkConnectionManagerCallback-9903-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1281928 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1281931 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1281934 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50078/solr ready
[junit4] 2> 1281936 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] 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:50090_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1281945 INFO (OverseerThreadFactory-9893-thread-1-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1282064 INFO (qtp1861873201-14885) [n:127.0.0.1:50090_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1282064 INFO (qtp1861873201-14885) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1282067 INFO (qtp1861873201-14882) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1282075 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283139 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1283155 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=sortable
[junit4] 2> 1283308 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema sortable/1.6 with uniqueid field id
[junit4] 2> 1283308 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1283309 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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@2d2385f2
[junit4] 2> 1283309 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1283312 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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=10, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.292461391035741]
[junit4] 2> 1283438 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283438 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283440 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1283440 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1283441 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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=30, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=20.1884765625, floorSegmentMB=1.056640625, forceMergeDeletesPctAllowed=9.10847195258388, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20863425053121915, deletesPctAllowed=29.646820494321325
[junit4] 2> 1283443 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1283444 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283445 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1283445 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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 1667496045660078080
[junit4] 2> 1283448 INFO (searcherExecutor-9905-thread-1-processing-n:127.0.0.1:50090_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'control_collection'
[junit4] 2> 1283462 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283462 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283473 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1283473 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283474 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50090/control_collection_shard1_replica_n1/
[junit4] 2> 1283475 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283476 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:50090/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1283476 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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/72091869411475460-core_node2-n_0000000000
[junit4] 2> 1283484 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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:50090/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1283589 INFO (zkCallback-9885-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> 1283589 INFO (zkCallback-9885-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> 1283593 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ 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> 1283603 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ ] 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=1528
[junit4] 2> 1283609 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1283701 INFO (zkCallback-9885-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> 1283701 INFO (zkCallback-9885-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> 1283701 INFO (zkCallback-9885-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> 1283704 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] 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:50090_&wt=javabin&version=2} status=0 QTime=1768
[junit4] 2> 1283705 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1283827 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1283837 INFO (zkConnectionManagerCallback-9914-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1283838 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1283841 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1283845 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50078/solr ready
[junit4] 2> 1283845 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1283849 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] 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> 1283858 INFO (OverseerThreadFactory-9893-thread-2-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1283858 INFO (OverseerCollectionConfigSetProcessor-72091869411475460-127.0.0.1:50090_-n_0000000000) [n:127.0.0.1:50090_ ] 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> 1284080 WARN (OverseerThreadFactory-9893-thread-2-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1284089 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1284094 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] 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=245
[junit4] 2> 1284097 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1284097 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1284099 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1284099 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1284099 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1284099 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1284099 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1284880 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1284883 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1284883 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1284883 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1284883 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1284885 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1284886 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1284886 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1284886 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40d12b41{/,null,AVAILABLE}
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2abd85c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50156}
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server Started @1284977ms
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/tempDir-001/jetty1, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=50156, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-1-001/cores}
[junit4] 2> 1284889 ERROR (closeThreadPool-9915-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1284889 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T15:35:52.899Z
[junit4] 2> 1284890 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1284903 INFO (zkConnectionManagerCallback-9917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1284903 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1285009 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1285009 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-1-001/solr.xml
[junit4] 2> 1285022 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1285022 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1285023 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1285102 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1285106 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6c392a14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1285106 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6c392a14[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1285121 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@72d373ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1285121 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@72d373ce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1285122 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50078/solr
[junit4] 2> 1285124 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1285127 INFO (zkConnectionManagerCallback-9928-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1285132 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1285246 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1285249 INFO (zkConnectionManagerCallback-9930-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1285249 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1285331 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1285381 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50156_ as DOWN
[junit4] 2> 1285388 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1285389 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50156_
[junit4] 2> 1285414 INFO (zkCallback-9885-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1285414 INFO (zkCallback-9913-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1285417 INFO (zkCallback-9929-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1285474 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1285475 WARN (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1285608 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1285788 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1285824 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1285825 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1285839 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50156_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-1-001/cores
[junit4] 2> 1285867 INFO (OverseerCollectionConfigSetProcessor-72091869411475460-127.0.0.1:50090_-n_0000000000) [n:127.0.0.1:50090_ ] 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> 1285959 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:50156_
[junit4] 2> 1286698 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1286703 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1286703 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1286703 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1286703 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1286717 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1286717 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1286717 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1286719 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3bf769ff{/,null,AVAILABLE}
[junit4] 2> 1286724 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@358976c2{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50166}
[junit4] 2> 1286724 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server Started @1286813ms
[junit4] 2> 1286724 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=50166, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-2-001/cores}
[junit4] 2> 1286726 ERROR (closeThreadPool-9915-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1286726 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1286726 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1286726 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1286726 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1286726 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T15:35:54.736Z
[junit4] 2> 1286728 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1286732 INFO (zkConnectionManagerCallback-9940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1286732 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1286841 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1286841 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-2-001/solr.xml
[junit4] 2> 1286907 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1286907 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1286908 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1287081 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1287083 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3bc227e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1287084 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3bc227e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1287092 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6fa05fa2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1287092 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6fa05fa2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1287093 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50078/solr
[junit4] 2> 1287097 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1287100 INFO (zkConnectionManagerCallback-9951-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1287101 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1287214 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1287219 INFO (zkConnectionManagerCallback-9953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1287219 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1287248 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1287324 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50166_ as DOWN
[junit4] 2> 1287331 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1287331 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50166_
[junit4] 2> 1287338 INFO (zkCallback-9929-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1287338 INFO (zkCallback-9885-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1287339 INFO (zkCallback-9913-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1287339 INFO (zkCallback-9952-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1287374 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1287374 WARN (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1287438 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1287474 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1287485 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1287485 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1287487 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50166_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-2-001/cores
[junit4] 2> 1287538 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:50166_
[junit4] 2> 1287739 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1287741 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1287741 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1287742 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1287742 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1287750 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1287750 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1287750 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1287751 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2df313e8{/,null,AVAILABLE}
[junit4] 2> 1287752 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@ee7af1d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50199}
[junit4] 2> 1287752 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server Started @1287841ms
[junit4] 2> 1287752 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/tempDir-001/jetty3, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=50199, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-3-001/cores}
[junit4] 2> 1287753 ERROR (closeThreadPool-9915-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1287754 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1287754 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1287755 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1287755 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1287755 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T15:35:55.765Z
[junit4] 2> 1287775 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1287781 INFO (zkConnectionManagerCallback-9963-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1287781 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1287933 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1287937 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-3-001/solr.xml
[junit4] 2> 1287981 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1287981 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1287987 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1288155 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1288158 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7da93bd3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1288158 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7da93bd3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1288173 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@261d11fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1288173 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@261d11fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1288175 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50078/solr
[junit4] 2> 1288177 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1288180 INFO (zkConnectionManagerCallback-9974-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1288181 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1288299 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1288305 INFO (zkConnectionManagerCallback-9976-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1288305 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1288353 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1288407 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50199_ as DOWN
[junit4] 2> 1288430 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1288430 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50199_
[junit4] 2> 1288444 INFO (zkCallback-9952-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1288446 INFO (zkCallback-9885-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1288446 INFO (zkCallback-9913-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1288446 INFO (zkCallback-9929-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1288449 INFO (zkCallback-9975-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1288475 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1288476 WARN (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1288602 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1288702 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1288732 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1288733 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1288755 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50199_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-3-001/cores
[junit4] 2> 1288858 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:50199_
[junit4] 2> 1289566 INFO (TEST-DistribCursorPagingTest.test-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1289567 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1289567 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1289567 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1289567 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 1289570 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1289570 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1289570 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ceb1e7{/,null,AVAILABLE}
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@26f2cab9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:50215}
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.e.j.s.Server Started @1289660ms
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=50215, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-4-001/cores}
[junit4] 2> 1289571 ERROR (closeThreadPool-9915-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-23T15:35:57.581Z
[junit4] 2> 1289571 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1289578 INFO (zkConnectionManagerCallback-9986-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1289578 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1289684 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1289684 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-4-001/solr.xml
[junit4] 2> 1289696 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1289696 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1289697 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1290196 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1290197 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d512170[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1290197 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d512170[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1290219 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4654f1c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1290219 WARN (closeThreadPool-9915-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4654f1c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1290221 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50078/solr
[junit4] 2> 1290222 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1290226 INFO (zkConnectionManagerCallback-9997-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1290226 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1290361 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1290365 INFO (zkConnectionManagerCallback-9999-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1290365 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1290386 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1290408 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50215_ as DOWN
[junit4] 2> 1290411 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1290411 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50215_
[junit4] 2> 1290418 INFO (zkCallback-9952-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290419 INFO (zkCallback-9885-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290419 INFO (zkCallback-9929-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290419 INFO (zkCallback-9913-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290419 INFO (zkCallback-9998-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290426 INFO (zkCallback-9975-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1290444 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1290444 WARN (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1290514 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1290573 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1290591 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1290591 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d2385f2
[junit4] 2> 1290593 INFO (closeThreadPool-9915-thread-1) [n:127.0.0.1:50215_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.cloud.DistribCursorPagingTest_46F4302BDD6C2A65-001/shard-4-001/cores
[junit4] 2> 1290679 INFO (closeThreadPool-9915-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:50215_
[junit4] 2> 1290707 INFO (qtp1861873201-14882) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50199_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1290713 INFO (qtp1861873201-14884) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50156_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1290713 INFO (qtp1861873201-14885) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50166_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1290714 INFO (qtp1861873201-14883) [n:127.0.0.1:50090_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50215_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1290842 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=1
[junit4] 2> 1290845 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290856 INFO (qtp780276938-14940) [n:127.0.0.1:50156_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290862 INFO (qtp1322343527-14970) [n:127.0.0.1:50166_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290874 INFO (qtp1790196314-14999) [n:127.0.0.1:50199_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290881 INFO (qtp499924989-15028) [n:127.0.0.1:50215_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1290883 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1290890 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290893 INFO (qtp780276938-14941) [n:127.0.0.1:50156_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1290909 INFO (qtp1322343527-14971) [n:127.0.0.1:50166_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290919 INFO (qtp1790196314-15000) [n:127.0.0.1:50199_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290936 INFO (qtp499924989-15029) [n:127.0.0.1:50215_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1290961 INFO (OverseerThreadFactory-9893-thread-4-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:50215_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 1290975 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=4
[junit4] 2> 1290979 INFO (qtp1861873201-15058) [n:127.0.0.1:50090_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1290986 INFO (OverseerThreadFactory-9893-thread-4-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1290986 INFO (qtp780276938-14942) [n:127.0.0.1:50156_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1291038 INFO (qtp1322343527-14972) [n:127.0.0.1:50166_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1291039 INFO (qtp499924989-15028) [n:127.0.0.1:50215_ x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1291044 INFO (qtp1790196314-15001) [n:127.0.0.1:50199_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1291052 INFO (qtp499924989-15029) [n:127.0.0.1:50215_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1291086 INFO (OverseerThreadFactory-9893-thread-3-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:50199_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 1291152 INFO (OverseerThreadFactory-9893-thread-3-processing-n:127.0.0.1:50090_) [n:127.0.0.1:50090_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1291163 INFO (qtp1790196314-15000) [n:127.0.0.1:50199_ x:collection1_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n3&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1292692 INFO (qtp1790196314-15000) [n:127.0.0.1:50199_ c:collection1 s:shard2 x:collection1_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1292724 INFO (qtp499924989-15028) [n:127.0.0.1:50215_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1292751 INFO (qtp1790196314-15000) [n:127.0.0.1:50199_ c:collection1 s:shard2 x:collection1_shard2_replica_n3 ] o.a.s.s.IndexSchema Schema name=sortable
[junit4] 2> 1292765 INFO (qtp499924989-15028) [n:127.0.0.1:50215_ c:collection1 s:s
[...truncated too long message...]
er@e231e50: rootName = solr_51195, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1a9504c2
[junit4] 2> 1419760 INFO (jetty-closer-13993-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4563e67a: rootName = solr_51196, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1a9504c2
[junit4] 2> 1419760 INFO (jetty-closer-13993-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1419760 INFO (jetty-closer-13993-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1419781 INFO (zkCallback-13930-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1419781 INFO (zkCallback-13928-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1419850 INFO (jetty-closer-13993-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72091878095650830-127.0.0.1:51199_solr-n_0000000000) closing
[junit4] 2> 1419862 INFO (jetty-closer-13993-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7c8b4fc1{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1419873 INFO (jetty-closer-13993-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4cdedb7{/solr,null,UNAVAILABLE}
[junit4] 2> 1419886 INFO (jetty-closer-13993-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1419894 INFO (jetty-closer-13993-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@64b07ec9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1419895 INFO (jetty-closer-13993-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3b6558a7{/solr,null,UNAVAILABLE}
[junit4] 2> 1419896 INFO (jetty-closer-13993-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1419998 INFO (jetty-closer-13993-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@696ed3e7{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1420003 INFO (jetty-closer-13993-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@72cc1f81{/solr,null,UNAVAILABLE}
[junit4] 2> 1420011 INFO (jetty-closer-13993-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1420103 INFO (jetty-closer-13993-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@36da917b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1420106 INFO (jetty-closer-13993-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@22add57a{/solr,null,UNAVAILABLE}
[junit4] 2> 1420110 INFO (jetty-closer-13993-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1420122 INFO (TEST-DeleteReplicaTest.deleteReplicaFromClusterStateLegacy-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1420353 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/collections/deleteFromClusterState_true/terms/shard1
[junit4] 2> 5 /solr/collections/deleteFromClusterState_true/state.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/deleteFromClusterState_true/collectionprops.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 37 /solr/collections/deleteFromClusterState_true/state.json
[junit4] 2> 10 /solr/clusterprops.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 29 /solr/overseer/queue
[junit4] 2> 22 /solr/live_nodes
[junit4] 2> 15 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1420362 INFO (TEST-DeleteReplicaTest.deleteReplicaFromClusterStateLegacy-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:51170
[junit4] 2> 1420362 INFO (TEST-DeleteReplicaTest.deleteReplicaFromClusterStateLegacy-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51170
[junit4] 2> 1420362 INFO (TEST-DeleteReplicaTest.deleteReplicaFromClusterStateLegacy-seed#[46F4302BDD6C2A65]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 51170
[junit4] 2> 1420363 INFO (TEST-DeleteReplicaTest.deleteReplicaFromClusterStateLegacy-seed#[46F4302BDD6C2A65]) [ ] o.a.s.SolrTestCaseJ4 ###Ending deleteReplicaFromClusterStateLegacy
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.DeleteReplicaTest_46F4302BDD6C2A65-001
[junit4] 2> May 23, 2020 3:38:08 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {_version_=PostingsFormat(name=LuceneFixedGap), _root_=PostingsFormat(name=LuceneFixedGap), id=Lucene84}, docValues:{}, maxPointsInLeafNode=2045, maxMBSortInHeap=5.2319065634747375, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5b46b05b), locale=en-US, timezone=Etc/GMT-3
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=146893424,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestReplicationHandler, TestBlobHandler, TestSizeLimitedDistributedMap, TestLFUCache, RankQueryTest, HighlighterConfigTest, TemplateUpdateProcessorTest, TestNRTOpen, TestSolrXml, FileBasedSpellCheckerTest, SynonymTokenizerTest, DistributedUpdateProcessorTest, PeerSyncWithBufferUpdatesTest, LeaderVoteWaitTimeoutTest, ReplaceNodeTest, HdfsWriteToMultipleCollectionsTest, TestPullReplica, TestZkChroot, TestLeaderElectionZkExpiry, ResponseBuilderTest, BJQFilterAccessibleTest, SOLR749Test, TestCollectionsAPIViaSolrCloudCluster, TestSimpleQParserPlugin, TestBulkSchemaAPI, TestXmlQParser, CollectionsAPISolrJTest, TestLMJelinekMercerSimilarityFactory, AdminHandlersProxyTest, ScheduledTriggerTest, TestAtomicUpdateErrorCases, TriggerCooldownIntegrationTest, XMLAtomicUpdateMultivalueTest, MultiThreadedOCPTest, TestCloudJSONFacetJoinDomain, DistributedQueryComponentOptimizationTest, TestDownShardTolerantSearch, TimeZoneUtilsTest, BasicFunctionalityTest, SolrTestCaseJ4Test, DistributedQueueTest, LeaderElectionIntegrationTest, TestManagedSchemaThreadSafety, TestStressVersions, MetricTriggerIntegrationTest, IndexSchemaRuntimeFieldTest, TestMissingGroups, TestSolrQueryResponse, Tagger2Test, TestMacroExpander, ClusterStateMockUtilTest, JWTVerificationkeyResolverTest, TransactionLogTest, FuzzySearchTest, TestFieldResource, HdfsRestartWhileUpdatingTest, TestCodecSupport, RegexBytesRefFilterTest, TestSimScenario, ClassificationUpdateProcessorIntegrationTest, CollectionsAPIAsyncDistributedZkTest, SSLMigrationTest, TestSimComputePlanAction, ZkSolrClientTest, TestDeprecatedFilters, TestLegacyTerms, TestFieldCacheSortRandom, BlockCacheTest, TestStressCloudBlindAtomicUpdates, TestCloudInspectUtil, TestIntervalFaceting, ChangedSchemaMergeTest, TestCloudNestedDocsSort, ZkCLITest, NodeLostTriggerTest, AutoAddReplicasIntegrationTest, SpellingQueryConverterTest, PluginInfoTest, TestConfigSetsAPIZkFailure, FacetPivotSmallTest, TestManagedSynonymFilterFactory, StatsComponentTest, TestDirectoryFactory, PrimUtilsTest, TestTolerantUpdateProcessorCloud, BasicAuthOnSingleNodeTest, TestFileDictionaryLookup, TestExactStatsCache, TestPayloadScoreQParserPlugin, CleanupOldIndexTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest]
[junit4] Completed [507/910 (2!)] on J1 in 153.35s, 9 tests, 1 error <<< FAILURES!
[...truncated 53767 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1035/consoleText
[repro] Revision: 5e7be63ca7fbd234526fceb2d7a0594a54f90670
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=DistribCursorPagingTest -Dtests.seed=46F4302BDD6C2A65 -Dtests.slow=true -Dtests.locale=ro-RO -Dtests.timezone=Africa/Addis_Ababa -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=DeleteReplicaTest -Dtests.method=deleteReplicaAndVerifyDirectoryCleanup -Dtests.seed=46F4302BDD6C2A65 -Dtests.slow=true -Dtests.locale=en-US -Dtests.timezone=Etc/GMT-3 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] DistribCursorPagingTest
[repro] DeleteReplicaTest
[repro] ant compile-test
[...truncated 3481 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.DistribCursorPagingTest|*.DeleteReplicaTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=46F4302BDD6C2A65 -Dtests.slow=true -Dtests.locale=ro-RO -Dtests.timezone=Africa/Addis_Ababa -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 442 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.DeleteReplicaTest
[repro] 0/5 failed: org.apache.solr.cloud.DistribCursorPagingTest
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-12.0.2) - Build # 1036
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1036/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.test
Error Message:
Could not get expected value 'P val' for path 'response/params/y/p' full output: { "responseHeader":{ "status":0, "QTime":0}, "response":{ "znodeVersion":0, "params":{"x":{ "a":"A val", "b":"B val", "":{"v":0}}}}}, from server: http://127.0.0.1:49501/dim/collection1_shard2_replica_n5
Stack Trace:
java.lang.AssertionError: Could not get expected value 'P val' for path 'response/params/y/p' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"response":{
"znodeVersion":0,
"params":{"x":{
"a":"A val",
"b":"B val",
"":{"v":0}}}}}, from server: http://127.0.0.1:49501/dim/collection1_shard2_replica_n5
at __randomizedtesting.SeedInfo.seed([F6548D7CFA5F5274:7E00B2A654A33F8C]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:244)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:51)
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:567)
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:1081)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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:835)
Build Log:
[...truncated 15799 lines...]
[junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
[junit4] 2> 1928165 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1928178 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/data-dir-155-001
[junit4] 2> 1928178 WARN (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12
[junit4] 2> 1928179 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1928179 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1928179 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1928179 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[F6548D7CFA5F5274]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /dim/
[junit4] 2> 1928196 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1928197 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1928197 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1928297 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer start zk server on port: 49401
[junit4] 2> 1928298 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:49401
[junit4] 2> 1928299 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49401
[junit4] 2> 1928299 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49401
[junit4] 2> 1928305 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1928328 INFO (zkConnectionManagerCallback-17615-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1928328 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1928351 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1928356 INFO (zkConnectionManagerCallback-17617-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1928357 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1928366 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1928391 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1928403 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1928406 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1928412 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1928416 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1928422 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1928424 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1928439 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1928441 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1928456 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer put /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1928461 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1929066 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1929075 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1929075 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1929075 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1929089 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1929090 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1929090 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1929091 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@289eb15d{/dim,null,AVAILABLE}
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@230c4a4e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49415}
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.s.Server Started @1929189ms
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dim, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/tempDir-001/control/data, hostPort=49415, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/control-001/cores, replicaType=NRT}
[junit4] 2> 1929112 ERROR (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1929112 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1929113 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1929113 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T02:36:47.020307Z
[junit4] 2> 1929118 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1929122 INFO (zkConnectionManagerCallback-17619-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1929123 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1929235 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1929235 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/control-001/solr.xml
[junit4] 2> 1929238 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1929238 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1929239 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1929495 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1929496 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@70fb051[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1929496 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@70fb051[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1929499 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@789785a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1929499 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@789785a8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1929500 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49401/solr
[junit4] 2> 1929501 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1929504 INFO (zkConnectionManagerCallback-17630-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1929504 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1929608 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1929610 INFO (zkConnectionManagerCallback-17632-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1929611 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1929920 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:49415_dim
[junit4] 2> 1929924 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.Overseer Overseer (id=72094468516741124-127.0.0.1:49415_dim-n_0000000000) starting
[junit4] 2> 1929956 INFO (OverseerStateUpdate-72094468516741124-127.0.0.1:49415_dim-n_0000000000) [n:127.0.0.1:49415_dim ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:49415_dim
[junit4] 2> 1929958 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49415_dim
[junit4] 2> 1929964 INFO (OverseerStateUpdate-72094468516741124-127.0.0.1:49415_dim-n_0000000000) [n:127.0.0.1:49415_dim ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1929977 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1929978 WARN (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1930000 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1930034 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1930058 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1930058 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1930061 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [n:127.0.0.1:49415_dim ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/control-001/cores
[junit4] 2> 1930070 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1930077 INFO (zkConnectionManagerCallback-17649-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1930077 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1930083 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1930088 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49401/solr ready
[junit4] 2> 1930092 INFO (qtp1477909353-25873) [n:127.0.0.1:49415_dim ] 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:49415_dim&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1930101 INFO (OverseerThreadFactory-17639-thread-1-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1930219 INFO (qtp1477909353-25875) [n:127.0.0.1:49415_dim ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1930219 INFO (qtp1477909353-25875) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1930222 INFO (qtp1477909353-25872) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1930234 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931256 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1931267 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1931341 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931387 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1931388 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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@4d4f1757
[junit4] 2> 1931388 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1931390 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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=29, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1931392 WARN (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931455 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931455 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931459 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1931459 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1931460 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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=20, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=72.8466796875, floorSegmentMB=0.42578125, forceMergeDeletesPctAllowed=3.9685091076850423, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5567707257277377, deletesPctAllowed=32.232014160701894
[junit4] 2> 1931463 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1931464 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931465 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1931465 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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 1667537630000054272
[junit4] 2> 1931469 INFO (searcherExecutor-17651-thread-1-processing-n:127.0.0.1:49415_dim x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'control_collection'
[junit4] 2> 1931478 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931478 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931486 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1931486 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931487 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:49415/dim/control_collection_shard1_replica_n1/
[junit4] 2> 1931487 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931488 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:49415/dim/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1931488 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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/72094468516741124-core_node2-n_0000000000
[junit4] 2> 1931492 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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:49415/dim/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1931605 INFO (zkCallback-17631-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> 1931605 INFO (zkCallback-17631-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> 1931608 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim 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> 1931612 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim ] 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=1378
[junit4] 2> 1931615 INFO (qtp1477909353-25873) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1931713 INFO (zkCallback-17631-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> 1931713 INFO (zkCallback-17631-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> 1931713 INFO (zkCallback-17631-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> 1931714 INFO (qtp1477909353-25873) [n:127.0.0.1:49415_dim ] 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:49415_dim&wt=javabin&version=2} status=0 QTime=1622
[junit4] 2> 1931715 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1931828 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1931838 INFO (zkConnectionManagerCallback-17660-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1931839 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1931853 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1931870 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:49401/solr ready
[junit4] 2> 1931870 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1931878 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim ] 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> 1931919 INFO (OverseerThreadFactory-17639-thread-2-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1931922 INFO (OverseerCollectionConfigSetProcessor-72094468516741124-127.0.0.1:49415_dim-n_0000000000) [n:127.0.0.1:49415_dim ] 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> 1932154 WARN (OverseerThreadFactory-17639-thread-2-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1932156 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1932158 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim ] 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=279
[junit4] 2> 1932159 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1932159 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1932160 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1932160 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1932160 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1932160 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1932160 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1932528 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1932530 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1932530 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1932530 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1932530 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1932532 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1932532 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1932533 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1932533 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cb68995{/dim,null,AVAILABLE}
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@224b8a7f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49477}
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.Server Started @1932611ms
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dim, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/tempDir-001/jetty1, hostPort=49477, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-1-001/cores, replicaType=NRT}
[junit4] 2> 1932534 ERROR (closeThreadPool-17661-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T02:36:50.441256Z
[junit4] 2> 1932534 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1932539 INFO (zkConnectionManagerCallback-17663-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1932539 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1932643 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1932643 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-1-001/solr.xml
[junit4] 2> 1932646 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1932646 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1932647 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1932750 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1932751 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@75a9f9a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1932751 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@75a9f9a4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1932755 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@722ede32[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1932756 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@722ede32[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1932757 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49401/solr
[junit4] 2> 1932758 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1932763 INFO (zkConnectionManagerCallback-17674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1932764 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1932869 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1932878 INFO (zkConnectionManagerCallback-17676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1932878 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1932908 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1932932 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.ZkController Publish node=127.0.0.1:49477_dim as DOWN
[junit4] 2> 1932939 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1932939 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49477_dim
[junit4] 2> 1932945 INFO (zkCallback-17675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1932945 INFO (zkCallback-17631-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1932947 INFO (zkCallback-17659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1932950 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1932954 WARN (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1932961 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1932962 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1932962 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1932962 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1932962 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1932966 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1932966 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1932966 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1932967 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@266f34df{/dim,null,AVAILABLE}
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6a9c400f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49490}
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.Server Started @1933044ms
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dim, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/tempDir-001/jetty2, hostPort=49490, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-2-001/cores, replicaType=NRT}
[junit4] 2> 1932968 ERROR (closeThreadPool-17661-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1932968 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T02:36:50.875394Z
[junit4] 2> 1932969 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1932972 INFO (zkConnectionManagerCallback-17681-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1932972 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1932990 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1933029 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933045 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933046 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933050 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49477_dim ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-1-001/cores
[junit4] 2> 1933060 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49477_dim
[junit4] 2> 1933076 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1933076 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-2-001/solr.xml
[junit4] 2> 1933079 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1933079 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1933080 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1933170 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1933173 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@d2c314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933173 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@d2c314[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933184 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@372732e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933184 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@372732e7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933186 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49401/solr
[junit4] 2> 1933191 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1933192 INFO (zkConnectionManagerCallback-17697-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1933193 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1933304 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1933310 INFO (zkConnectionManagerCallback-17699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1933310 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1933333 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1933347 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.ZkController Publish node=127.0.0.1:49490_dim as DOWN
[junit4] 2> 1933351 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1933351 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49490_dim
[junit4] 2> 1933356 INFO (zkCallback-17659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1933356 INFO (zkCallback-17631-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1933356 INFO (zkCallback-17675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1933357 INFO (zkCallback-17698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1933363 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1933363 WARN (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1933396 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1933443 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933463 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933463 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1933463 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1933464 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1933464 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1933464 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1933464 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1933466 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49490_dim ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-2-001/cores
[junit4] 2> 1933472 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1933472 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1933472 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1933473 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d855f9a{/dim,null,AVAILABLE}
[junit4] 2> 1933475 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4bc1eb8c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49501}
[junit4] 2> 1933475 INFO (closeThreadPool-17661-thread-1) [ ] o.e.j.s.Server Started @1933552ms
[junit4] 2> 1933475 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dim, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/tempDir-001/jetty3, hostPort=49501, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-3-001/cores}
[junit4] 2> 1933476 ERROR (closeThreadPool-17661-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1933477 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1933477 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1933477 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1933477 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1933477 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T02:36:51.384418Z
[junit4] 2> 1933479 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1933483 INFO (zkConnectionManagerCallback-17709-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1933483 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1933500 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49490_dim
[junit4] 2> 1933600 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1933600 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-3-001/solr.xml
[junit4] 2> 1933603 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1933603 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1933604 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1933777 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1933780 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1251d492[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933780 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1251d492[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933786 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a483402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933787 WARN (closeThreadPool-17661-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a483402[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1933791 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49401/solr
[junit4] 2> 1933794 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1933796 INFO (zkConnectionManagerCallback-17720-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1933797 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1933909 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1933913 INFO (zkConnectionManagerCallback-17722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1933914 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1933934 INFO (OverseerCollectionConfigSetProcessor-72094468516741124-127.0.0.1:49415_dim-n_0000000000) [n:127.0.0.1:49415_dim ] 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> 1933955 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1933983 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.ZkController Publish node=127.0.0.1:49501_dim as DOWN
[junit4] 2> 1933990 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1933991 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49501_dim
[junit4] 2> 1933999 INFO (zkCallback-17675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1933999 INFO (zkCallback-17698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1933999 INFO (zkCallback-17659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1934001 INFO (zkCallback-17631-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1934003 INFO (zkCallback-17721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1934016 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1934016 WARN (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1934054 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1934089 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934110 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934110 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934113 INFO (closeThreadPool-17661-thread-1) [n:127.0.0.1:49501_dim ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-3-001/cores
[junit4] 2> 1934127 INFO (closeThreadPool-17661-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49501_dim
[junit4] 2> 1934128 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1934130 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1934130 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1934130 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1934130 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
[junit4] 2> 1934134 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1934134 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1934134 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1934135 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2726b46d{/dim,null,AVAILABLE}
[junit4] 2> 1934136 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b4dabe4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:49528}
[junit4] 2> 1934136 INFO (closeThreadPool-17661-thread-2) [ ] o.e.j.s.Server Started @1934213ms
[junit4] 2> 1934136 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/dim, solrconfig=solrconfig.xml, solr.data.dir=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/tempDir-001/jetty4, hostPort=49528, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-4-001/cores}
[junit4] 2> 1934136 ERROR (closeThreadPool-17661-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1934136 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1934136 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1934137 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1934137 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1934137 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-24T02:36:52.044022Z
[junit4] 2> 1934138 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1934141 INFO (zkConnectionManagerCallback-17732-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1934141 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1934246 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1934247 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-4-001/solr.xml
[junit4] 2> 1934251 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1934251 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1934252 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1934370 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1934372 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@30ada75f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1934372 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@30ada75f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1934376 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5369b7f6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1934376 WARN (closeThreadPool-17661-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5369b7f6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1934377 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49401/solr
[junit4] 2> 1934380 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1934389 INFO (zkConnectionManagerCallback-17743-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1934389 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1934503 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1934507 INFO (zkConnectionManagerCallback-17745-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1934507 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1934532 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1934548 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.ZkController Publish node=127.0.0.1:49528_dim as DOWN
[junit4] 2> 1934550 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1934550 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49528_dim
[junit4] 2> 1934558 INFO (zkCallback-17675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934558 INFO (zkCallback-17698-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934558 INFO (zkCallback-17721-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934559 INFO (zkCallback-17631-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934559 INFO (zkCallback-17659-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934559 INFO (zkCallback-17744-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1934565 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1934566 WARN (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1934587 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1934612 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934628 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934628 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4d4f1757
[junit4] 2> 1934630 INFO (closeThreadPool-17661-thread-2) [n:127.0.0.1:49528_dim ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001/shard-4-001/cores
[junit4] 2> 1934639 INFO (closeThreadPool-17661-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:49528_dim
[junit4] 2> 1934643 INFO (qtp1477909353-25875) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:49477_dim&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1934645 INFO (qtp1477909353-25873) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:49490_dim&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1934646 INFO (qtp1477909353-25872) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:49501_dim&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1934647 INFO (qtp1477909353-25874) [n:127.0.0.1:49415_dim ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:49528_dim&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1934669 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1934670 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934673 INFO (qtp645139049-25928) [n:127.0.0.1:49477_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934676 INFO (qtp1856231683-25953) [n:127.0.0.1:49490_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934680 INFO (qtp891108023-25985) [n:127.0.0.1:49501_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934684 INFO (qtp1154177880-26017) [n:127.0.0.1:49528_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934686 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1934687 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934689 INFO (qtp645139049-25929) [n:127.0.0.1:49477_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934690 INFO (qtp1856231683-25954) [n:127.0.0.1:49490_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934691 INFO (qtp891108023-25986) [n:127.0.0.1:49501_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934692 INFO (qtp1154177880-26018) [n:127.0.0.1:49528_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934693 INFO (OverseerThreadFactory-17639-thread-4-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:49490_dim for creating new replica of shard shard1 for collection collection1
[junit4] 2> 1934700 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1934701 INFO (qtp1477909353-26047) [n:127.0.0.1:49415_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934703 INFO (qtp645139049-25930) [n:127.0.0.1:49477_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934704 INFO (OverseerThreadFactory-17639-thread-4-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1934704 INFO (qtp1856231683-25955) [n:127.0.0.1:49490_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934706 INFO (qtp891108023-25987) [n:127.0.0.1:49501_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934707 INFO (qtp1154177880-26019) [n:127.0.0.1:49528_dim ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 1934714 INFO (qtp1856231683-25954) [n:127.0.0.1:49490_dim x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1934714 INFO (OverseerThreadFactory-17639-thread-3-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:49477_dim for creating new replica of shard shard2 for collection collection1
[junit4] 2> 1934719 INFO (OverseerThreadFactory-17639-thread-3-processing-n:127.0.0.1:49415_dim) [n:127.0.0.1:49415_dim c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1934727 INFO (qtp645139049-25929) [n:127.0.0.1:49477_dim x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1935741 INFO (qtp1856231683-25954) [n:127.0.0.1:49490_dim c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1935750 INFO (qtp645139049-25929) [n:127.0.0.1:49477_dim c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1935759 INFO (qtp1856231683-25954) [n:127.0.0.1:49490_dim c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1935766 INFO (qtp645139049-25929) [n:127.0.0.1:49477_dim c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=test
[junit4
[...truncated too long message...]
(closeThreadPool-21250-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@224b8a7f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2036085 INFO (closeThreadPool-21250-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@cb68995{/dim,null,UNAVAILABLE}
[junit4] 2> 2036086 INFO (closeThreadPool-21250-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2036185 INFO (closeThreadPool-21250-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1b4dabe4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2036185 INFO (closeThreadPool-21250-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2726b46d{/dim,null,UNAVAILABLE}
[junit4] 2> 2036185 INFO (closeThreadPool-21250-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2036188 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2036404 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 135 /solr/configs/conf1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 83 /solr/collections/collection1/state.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 3 /solr/overseer_elect/election/72094468516741129-127.0.0.1:49477_dim-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/overseer/queue
[junit4] 2> 34 /solr/live_nodes
[junit4] 2> 14 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/collections
[junit4] 2>
[junit4] 2> 2036411 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:49401
[junit4] 2> 2036411 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:49401
[junit4] 2> 2036411 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[F6548D7CFA5F5274]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 49401
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=test -Dtests.seed=F6548D7CFA5F5274 -Dtests.slow=true -Dtests.locale=qu-EC -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 108s J0 | TestSolrConfigHandlerCloud.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not get expected value 'P val' for path 'response/params/y/p' full output: {
[junit4] > "responseHeader":{
[junit4] > "status":0,
[junit4] > "QTime":0},
[junit4] > "response":{
[junit4] > "znodeVersion":0,
[junit4] > "params":{"x":{
[junit4] > "a":"A val",
[junit4] > "b":"B val",
[junit4] > "":{"v":0}}}}}, from server: http://127.0.0.1:49501/dim/collection1_shard2_replica_n5
[junit4] > at __randomizedtesting.SeedInfo.seed([F6548D7CFA5F5274:7E00B2A654A33F8C]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
[junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqParams(TestSolrConfigHandlerCloud.java:244)
[junit4] > at org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:51)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1081)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.handler.TestSolrConfigHandlerCloud_F6548D7CFA5F5274-001
[junit4] 2> May 24, 2020 2:38:34 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2d310e2c), locale=qu-EC, timezone=Pacific/Truk
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=310132456,total=526909440
[junit4] 2> NOTE: All tests run in this JVM: [TestSort, IndexSizeEstimatorTest, BitVectorTest, SpatialHeatmapFacetsTest, TestWithCollection, TestSchemaResource, BooleanFieldTest, TestPostingsSolrHighlighter, ResourceLoaderTest, TestSolrIndexConfig, XCJFQueryTest, TestTlogReplica, TestRecovery, TestJavabinTupleStreamParser, DeleteReplicaTest, TestExportTool, CdcrUpdateLogTest, IndexSizeTriggerTest, HdfsUnloadDistributedZkTest, ZkStateReaderTest, TestBlendedInfixSuggestions, TestSimPolicyCloud, TestRTGBase, TestMergePolicyConfig, DistributedQueryComponentCustomSortTest, MigrateRouteKeyTest, TestPivotHelperCode, TestNumericTokenStream, NumericFieldsTest, ClassificationUpdateProcessorFactoryTest, SplitShardTest, TestDistributedMissingSort, TestSolrCoreSnapshots, TestFieldCacheSort, CachingDirectoryFactoryTest, BasicAuthIntegrationTest, GraphQueryTest, ReplaceNodeNoTargetTest, TestPerFieldSimilarityWithDefaultOverride, CloudExitableDirectoryReaderTest, RandomizedTaggerTest, HdfsRecoveryZkTest, UpdateRequestProcessorFactoryTest, HdfsLockFactoryTest, TestCharFilters, TestPrepRecovery, SpatialRPTFieldTypeTest, IgnoreLargeDocumentProcessorFactoryTest, TestConfigSetProperties, TestRetrieveFieldsOptimizer, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DimensionalRoutedAliasUpdateProcessorTest, TestSearcherReuse, TestShortCircuitedRequests, TestCloudSchemaless, TestNumericTerms32, TestJoin, PrimitiveFieldTypeTest, TestSolrCloudWithSecureImpersonation, SolrIndexSplitterTest, ZookeeperStatusHandlerTest, TestUseDocValuesAsStored2, QueryElevationComponentTest, TestBM25SimilarityFactory, TestRequestStatusCollectionAPI, CdcrRequestHandlerTest, TestJsonFacetRefinement, XsltUpdateRequestHandlerTest, SpellCheckComponentTest, CreateCollectionCleanupTest, TestStressUserVersions, DistribJoinFromCollectionTest, OrderedExecutorTest, TestPayloadCheckQParserPlugin, FieldAnalysisRequestHandlerTest, TestFiltering, TokenizerChainTest, TestSolrTestCaseJ4, SpellPossibilityIteratorTest, CustomCollectionTest, BaseCdcrDistributedZkTest, SortSpecParsingTest, SolrInfoBeanTest, TestSimExtremeIndexing, CursorMarkTest, DirectSolrSpellCheckerTest, RegexBoostProcessorTest, FastVectorHighlighterTest, TestUtils, LeaderElectionContextKeyTest, LeaderElectionTest, LeaderTragicEventTest, LeaderVoteWaitTimeoutTest, OverseerRolesTest, PeerSyncReplicationTest, TestCloudSearcherWarming, TestLeaderElectionZkExpiry, TestRebalanceLeaders, AssignTest, CollectionReloadTest, CollectionsAPIDistributedZkTest, RestoreTriggerStateTest, TestPolicyCloud, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimDistribStateManager, HdfsBasicDistributedZkTest, HdfsChaosMonkeySafeLeaderTest, HdfsRecoverLeaseTest, RequestHandlersTest, TestCodecSupport, TestConfigSetImmutable, TestDynamicURP, TestImplicitCoreProperties, TestInfoStreamLogging, TestInitParams, TestJmxIntegration, TestLazyCores, TestConfigReload, TestRestoreCore, TestSolrConfigHandlerCloud]
[junit4] Completed [709/910 (1!)] on J0 in 109.39s, 1 test, 1 failure <<< FAILURES!
[...truncated 48372 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1036/consoleText
[repro] Revision: 5e7be63ca7fbd234526fceb2d7a0594a54f90670
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestSolrConfigHandlerCloud -Dtests.method=test -Dtests.seed=F6548D7CFA5F5274 -Dtests.slow=true -Dtests.locale=qu-EC -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestSolrConfigHandlerCloud
[repro] ant compile-test
[...truncated 3486 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSolrConfigHandlerCloud" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=F6548D7CFA5F5274 -Dtests.slow=true -Dtests.locale=qu-EC -Dtests.timezone=Pacific/Truk -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 96 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.handler.TestSolrConfigHandlerCloud
[repro] Exiting with code 0
[...truncated 73 lines...]