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/03 06:40:02 UTC
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk1.8.0) - Build # 936 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/936/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestSolrConfigHandlerConcurrent
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.TestSolrConfigHandlerConcurrent: 1) Thread[id=11882, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSolrConfigHandlerConcurrent] 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.handler.TestSolrConfigHandlerConcurrent:
1) Thread[id=11882, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSolrConfigHandlerConcurrent]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([3B43CE67D47B992B]:0)
FAILED: org.apache.solr.client.solrj.TestLBHttp2SolrClient.testTwoServers
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:51170/solr/collection1/select?q=*%3A*&wt=javabin&version=2
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:51170/solr/collection1/select?q=*%3A*&wt=javabin&version=2
at __randomizedtesting.SeedInfo.seed([CE8E5C5E05AF6BA1:6E64F2D3DEE44581]:0)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:407)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:753)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:605)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:581)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.client.solrj.TestLBHttp2SolrClient.testTwoServers(TestLBHttp2SolrClient.java:186)
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)
Caused by: java.util.concurrent.TimeoutException
at org.eclipse.jetty.client.util.InputStreamResponseListener.get(InputStreamResponseListener.java:216)
at org.apache.solr.client.solrj.impl.Http2SolrClient.request(Http2SolrClient.java:398)
... 46 more
Build Log:
[...truncated 14684 lines...]
[junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerConcurrent
[junit4] 2> 1045580 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-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> 1045580 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1045581 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/data-dir-155-001
[junit4] 2> 1045581 WARN (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1045582 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1045593 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1045594 INFO (SUITE-TestSolrConfigHandlerConcurrent-seed#[3B43CE67D47B992B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_bj/kc
[junit4] 2> 1045599 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1045600 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1045600 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1045700 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkTestServer start zk server on port: 53242
[junit4] 2> 1045700 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:53242
[junit4] 2> 1045700 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:53242
[junit4] 2> 1045700 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 53242
[junit4] 2> 1045702 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1045707 INFO (zkConnectionManagerCallback-7651-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1045707 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1045711 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1045714 INFO (zkConnectionManagerCallback-7653-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1045714 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1045718 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045722 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045726 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045731 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045737 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045740 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045744 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045748 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045753 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045756 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045760 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1045763 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1046246 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1046246 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1046246 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1046246 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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> 1046265 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1046265 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1046265 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1046271 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c600dbb{/_bj/kc,null,AVAILABLE}
[junit4] 2> 1046272 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@102020e9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53254}
[junit4] 2> 1046272 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.s.Server Started @1046386ms
[junit4] 2> 1046272 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/tempDir-001/control/data, hostContext=/_bj/kc, hostPort=53254, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/control-001/cores}
[junit4] 2> 1046273 ERROR (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1046273 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1046273 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1046273 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1046273 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1046273 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T05:51:56.574Z
[junit4] 2> 1046274 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1046277 INFO (zkConnectionManagerCallback-7655-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1046277 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1046381 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1046381 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/control-001/solr.xml
[junit4] 2> 1046389 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1046389 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1046390 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1046565 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1046586 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@450c9554[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1046586 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@450c9554[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1046618 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5a142a30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1046618 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5a142a30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1046625 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53242/solr
[junit4] 2> 1046644 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1046646 INFO (zkConnectionManagerCallback-7666-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1046646 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1046756 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1046760 INFO (zkConnectionManagerCallback-7668-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1046761 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1047145 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53254__bj%2Fkc
[junit4] 2> 1047148 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.Overseer Overseer (id=72152026228654084-127.0.0.1:53254__bj%2Fkc-n_0000000000) starting
[junit4] 2> 1047182 INFO (OverseerStateUpdate-72152026228654084-127.0.0.1:53254__bj%2Fkc-n_0000000000) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:53254__bj%2Fkc
[junit4] 2> 1047185 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53254__bj%2Fkc
[junit4] 2> 1047192 INFO (zkCallback-7667-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1047198 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1047198 WARN (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1047246 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1047275 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1047284 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1047284 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1047292 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [n:127.0.0.1:53254__bj%2Fkc ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/control-001/cores
[junit4] 2> 1047342 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1047346 INFO (zkConnectionManagerCallback-7685-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1047346 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1047348 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1047351 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53242/solr ready
[junit4] 2> 1047353 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] 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:53254__bj%252Fkc&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1047359 INFO (OverseerThreadFactory-7675-thread-1-processing-n:127.0.0.1:53254__bj%2Fkc) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1047481 INFO (qtp1913480461-11910) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1047484 INFO (qtp1913480461-11907) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1047492 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1047493 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1048527 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1048542 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1048664 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048700 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048700 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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@188346c1
[junit4] 2> 1048700 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1048702 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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=18, maxMergeAtOnceExplicit=11, maxMergedSegmentMB=43.9140625, floorSegmentMB=0.6376953125, forceMergeDeletesPctAllowed=16.01842892663855, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.104209088880521, deletesPctAllowed=43.24750498694429
[junit4] 2> 1048704 WARN (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048794 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048794 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048796 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1048796 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1048797 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1048798 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3dc732c[control_collection_shard1_replica_n1] main]
[junit4] 2> 1048801 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1048802 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048803 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048803 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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 1665647372249595904
[junit4] 2> 1048807 INFO (searcherExecutor-7687-thread-1-processing-n:127.0.0.1:53254__bj%2Fkc x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@3dc732c[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1048825 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048826 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048839 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1048839 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048839 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53254/_bj/kc/control_collection_shard1_replica_n1/
[junit4] 2> 1048840 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048842 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:53254/_bj/kc/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1048842 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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/72152026228654084-core_node2-n_0000000000
[junit4] 2> 1048848 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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:53254/_bj/kc/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1048957 INFO (zkCallback-7667-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> 1048957 INFO (zkCallback-7667-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> 1048963 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc 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> 1048968 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc ] 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=1476
[junit4] 2> 1048973 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1049070 INFO (zkCallback-7667-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> 1049070 INFO (zkCallback-7667-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> 1049070 INFO (zkCallback-7667-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> 1049072 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] 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:53254__bj%252Fkc&wt=javabin&version=2} status=0 QTime=1718
[junit4] 2> 1049072 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1049185 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1049189 INFO (zkConnectionManagerCallback-7696-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1049189 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1049191 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1049195 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:53242/solr ready
[junit4] 2> 1049195 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1049196 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1049204 INFO (OverseerThreadFactory-7675-thread-2-processing-n:127.0.0.1:53254__bj%2Fkc) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1049204 INFO (OverseerCollectionConfigSetProcessor-72152026228654084-127.0.0.1:53254__bj%2Fkc-n_0000000000) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1049422 WARN (OverseerThreadFactory-7675-thread-2-processing-n:127.0.0.1:53254__bj%2Fkc) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1049428 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1049436 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] 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=240
[junit4] 2> 1049439 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1049439 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1049440 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1049440 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1049442 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected: 2
[junit4] 2> 1049442 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1049442 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1049925 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1049925 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1049925 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1049925 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1049925 INFO (closeThreadPool-7697-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> 1049930 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1049930 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1049930 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1049931 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@65d7fc9b{/_bj/kc,null,AVAILABLE}
[junit4] 2> 1049931 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5be5a8bc{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53281}
[junit4] 2> 1049931 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.Server Started @1050045ms
[junit4] 2> 1049931 INFO (closeThreadPool-7697-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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/tempDir-001/jetty1, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/_bj/kc, hostPort=53281, 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-1-001/cores}
[junit4] 2> 1049932 ERROR (closeThreadPool-7697-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1049932 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1049932 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1049932 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1049932 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1049932 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T05:52:00.233Z
[junit4] 2> 1049933 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1049936 INFO (zkConnectionManagerCallback-7699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1049936 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050047 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1050048 INFO (closeThreadPool-7697-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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-1-001/solr.xml
[junit4] 2> 1050056 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1050056 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1050057 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1050176 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1050179 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3410b0c3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050179 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3410b0c3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050188 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5a574748[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050188 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5a574748[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050189 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53242/solr
[junit4] 2> 1050191 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1050195 INFO (zkConnectionManagerCallback-7710-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1050195 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050299 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1050302 INFO (zkConnectionManagerCallback-7712-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1050303 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050345 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1050374 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.ZkController Publish node=127.0.0.1:53281__bj%2Fkc as DOWN
[junit4] 2> 1050387 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1050388 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53281__bj%2Fkc
[junit4] 2> 1050394 INFO (zkCallback-7667-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1050394 INFO (zkCallback-7695-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1050395 INFO (zkCallback-7711-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1050407 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1050407 WARN (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] 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> 1050475 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1050519 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050530 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050530 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050536 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53281__bj%2Fkc ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-1-001/cores
[junit4] 2> 1050567 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1050569 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1050569 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1050569 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1050569 INFO (closeThreadPool-7697-thread-2) [ ] 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> 1050571 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1050571 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1050572 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1050573 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@62aaf83b{/_bj/kc,null,AVAILABLE}
[junit4] 2> 1050574 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7fe46892{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53291}
[junit4] 2> 1050574 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.Server Started @1050688ms
[junit4] 2> 1050575 INFO (closeThreadPool-7697-thread-2) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/_bj/kc, hostPort=53291, 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-2-001/cores}
[junit4] 2> 1050575 ERROR (closeThreadPool-7697-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1050575 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1050575 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1050576 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1050576 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1050576 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T05:52:00.877Z
[junit4] 2> 1050578 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1050579 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:53281__bj%2Fkc
[junit4] 2> 1050584 INFO (zkConnectionManagerCallback-7722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1050584 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050688 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1050688 INFO (closeThreadPool-7697-thread-2) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-2-001/solr.xml
[junit4] 2> 1050695 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1050695 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1050696 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1050747 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1050748 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@31716bab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050748 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@31716bab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050752 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@8d1da8d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050752 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@8d1da8d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1050753 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53242/solr
[junit4] 2> 1050754 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1050757 INFO (zkConnectionManagerCallback-7733-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1050757 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050859 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1050863 INFO (zkConnectionManagerCallback-7735-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1050864 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1050876 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1050884 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.ZkController Publish node=127.0.0.1:53291__bj%2Fkc as DOWN
[junit4] 2> 1050889 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1050889 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53291__bj%2Fkc
[junit4] 2> 1050893 INFO (zkCallback-7695-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1050893 INFO (zkCallback-7711-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1050893 INFO (zkCallback-7667-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1050893 INFO (zkCallback-7734-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1050898 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1050898 WARN (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] 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> 1050948 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1050976 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050985 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050985 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1050986 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53291__bj%2Fkc ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-2-001/cores
[junit4] 2> 1051037 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1051039 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1051040 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1051040 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1051040 INFO (closeThreadPool-7697-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> 1051041 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:53291__bj%2Fkc
[junit4] 2> 1051043 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1051043 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1051043 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1051044 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5957853b{/_bj/kc,null,AVAILABLE}
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2a85b50c{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53300}
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.e.j.s.Server Started @1051159ms
[junit4] 2> 1051045 INFO (closeThreadPool-7697-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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/_bj/kc, hostPort=53300, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-3-001/cores}
[junit4] 2> 1051045 ERROR (closeThreadPool-7697-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1051045 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T05:52:01.346Z
[junit4] 2> 1051046 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051050 INFO (zkConnectionManagerCallback-7745-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051051 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1051162 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1051162 INFO (closeThreadPool-7697-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/temp/solr.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-3-001/solr.xml
[junit4] 2> 1051171 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1051171 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1051172 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1051208 INFO (OverseerCollectionConfigSetProcessor-72152026228654084-127.0.0.1:53254__bj%2Fkc-n_0000000000) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1051284 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1051284 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4e2f5eb7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051284 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4e2f5eb7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051293 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1a2c3217[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051293 WARN (closeThreadPool-7697-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1a2c3217[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051295 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53242/solr
[junit4] 2> 1051296 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051299 INFO (zkConnectionManagerCallback-7756-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051299 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1051406 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051409 INFO (zkConnectionManagerCallback-7758-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051410 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1051432 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1051453 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.ZkController Publish node=127.0.0.1:53300__bj%2Fkc as DOWN
[junit4] 2> 1051464 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1051465 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53300__bj%2Fkc
[junit4] 2> 1051471 INFO (zkCallback-7695-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1051471 INFO (zkCallback-7711-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1051471 INFO (zkCallback-7734-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1051471 INFO (zkCallback-7757-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1051473 INFO (zkCallback-7667-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1051491 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1051491 WARN (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] 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> 1051562 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1051609 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1051624 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1051625 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1051627 INFO (closeThreadPool-7697-thread-1) [n:127.0.0.1:53300__bj%2Fkc ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-3-001/cores
[junit4] 2> 1051648 INFO (TEST-TestSolrConfigHandlerConcurrent.test-seed#[3B43CE67D47B992B]) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1051649 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1051649 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1051649 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1051650 INFO (closeThreadPool-7697-thread-2) [ ] 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> 1051653 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1051653 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1051653 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1051654 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2423adc1{/_bj/kc,null,AVAILABLE}
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@56c81174{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:53308}
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.e.j.s.Server Started @1051769ms
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/_bj/kc, hostPort=53308, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J4/temp/solr.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-4-001/cores}
[junit4] 2> 1051655 ERROR (closeThreadPool-7697-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1051655 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T05:52:01.956Z
[junit4] 2> 1051658 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051662 INFO (zkConnectionManagerCallback-7768-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051662 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1051700 INFO (closeThreadPool-7697-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:53300__bj%2Fkc
[junit4] 2> 1051765 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1051765 INFO (closeThreadPool-7697-thread-2) [ ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-4-001/solr.xml
[junit4] 2> 1051772 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1051772 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1051774 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1051876 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1051877 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28ca20c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051877 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28ca20c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051879 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@14f8a0b3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051880 WARN (closeThreadPool-7697-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@14f8a0b3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1051881 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53242/solr
[junit4] 2> 1051882 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051884 INFO (zkConnectionManagerCallback-7779-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051885 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1051989 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1051992 INFO (zkConnectionManagerCallback-7781-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1051992 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1052006 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1052018 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.ZkController Publish node=127.0.0.1:53308__bj%2Fkc as DOWN
[junit4] 2> 1052020 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1052020 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53308__bj%2Fkc
[junit4] 2> 1052024 INFO (zkCallback-7734-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052024 INFO (zkCallback-7667-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052025 INFO (zkCallback-7757-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052025 INFO (zkCallback-7695-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052025 INFO (zkCallback-7711-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052025 INFO (zkCallback-7780-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1052031 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1052032 WARN (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] 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> 1052086 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1052138 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1052221 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1052221 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@188346c1
[junit4] 2> 1052224 INFO (closeThreadPool-7697-thread-2) [n:127.0.0.1:53308__bj%2Fkc ] 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.handler.TestSolrConfigHandlerConcurrent_3B43CE67D47B992B-001/shard-4-001/cores
[junit4] 2> 1052274 INFO (closeThreadPool-7697-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:53308__bj%2Fkc
[junit4] 2> 1052291 INFO (qtp1913480461-11907) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:53281__bj%252Fkc&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1052293 INFO (qtp1913480461-11908) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:53308__bj%252Fkc&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1052293 INFO (qtp1913480461-11909) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:53300__bj%252Fkc&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1052295 INFO (qtp1913480461-11910) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:53291__bj%252Fkc&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1052326 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] 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=2
[junit4] 2> 1052328 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052331 INFO (qtp1608136607-11964) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052335 INFO (qtp2078012209-11993) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1052338 INFO (qtp1760440677-12022) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052341 INFO (qtp205804718-12051) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052351 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1052353 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1052356 INFO (qtp1608136607-11965) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052359 INFO (qtp2078012209-11994) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052363 INFO (qtp1760440677-12023) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052366 INFO (qtp205804718-12052) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052369 INFO (OverseerThreadFactory-7675-thread-4-processing-n:127.0.0.1:53254__bj%2Fkc) [n:127.0.0.1:53254__bj%2Fkc c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:53308__bj%2Fkc for creating new replica of shard shard1 for collection collection1
[junit4] 2> 1052373 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] 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> 1052375 INFO (qtp1913480461-12083) [n:127.0.0.1:53254__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052377 INFO (OverseerThreadFactory-7675-thread-4-processing-n:127.0.0.1:53254__bj%2Fkc) [n:127.0.0.1:53254__bj%2Fkc c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1052377 INFO (qtp1608136607-11966) [n:127.0.0.1:53281__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052378 INFO (qtp2078012209-11995) [n:127.0.0.1:53291__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052380 INFO (qtp1760440677-12024) [n:127.0.0.1:53300__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1052383 INFO (qtp205804718-12053) [n:127.0.0.1:53308__bj%2Fkc ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&w
[...truncated too long message...]
cs.MetricRegistry@31ac8627
[junit4] 2> 46341 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@125286af{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 46341 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@64b7e897{/solr,null,UNAVAILABLE}
[junit4] 2> 46341 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 46355 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1111140373
[junit4] 2> 46358 INFO (coreCloseExecutor-246-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e67dce
[junit4] 2> 46358 INFO (coreCloseExecutor-246-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@e67dce
[junit4] 2> 46358 INFO (coreCloseExecutor-246-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@43eb8ff6: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@357de6ab
[junit4] 2> 46381 INFO (coreCloseExecutor-246-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 46426 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 46426 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f4c50b5: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@5613e7bf
[junit4] 2> 46439 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 46439 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@53c0fa: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@cbe32ee
[junit4] 2> 46442 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 46442 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@74b04b5e: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@31ac8627
[junit4] 2> 46452 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@19a66a8b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:51247}
[junit4] 2> 46453 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7d8a57ed{/solr,null,UNAVAILABLE}
[junit4] 2> 46453 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 46458 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=33373458
[junit4] 2> 46458 INFO (coreCloseExecutor-250-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6860a304
[junit4] 2> 46458 INFO (coreCloseExecutor-250-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@6860a304
[junit4] 2> 46458 INFO (coreCloseExecutor-250-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@752b47f5: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@4c08132e
[junit4] 2> 46465 INFO (coreCloseExecutor-250-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 46482 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 46482 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f8d91e8: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@1289a5ee
[junit4] 2> 46483 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 46483 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@66050654: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@cbe32ee
[junit4] 2> 46484 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 46484 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@338fad9f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@31ac8627
[junit4] 2> 46490 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2d46899a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 46490 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@222eefda{/solr,null,UNAVAILABLE}
[junit4] 2> 46490 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 46505 INFO (TEST-TestLBHttp2SolrClient.testReliability-seed#[CE8E5C5E05AF6BA1]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReliability
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.TestLBHttp2SolrClient_CE8E5C5E05AF6BA1-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {name=FST50, id=FST50}, docValues:{}, maxPointsInLeafNode=1013, maxMBSortInHeap=6.572571275781521, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7e002cc6), locale=zh-TW, timezone=Asia/Harbin
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=84773720,total=194772992
[junit4] 2> NOTE: All tests run in this JVM: [ConcurrentUpdateSolrClientBuilderTest, TestLBHttp2SolrClient]
[junit4] Completed [10/208 (1!)] on J3 in 37.20s, 3 tests, 1 error <<< FAILURES!
[...truncated 52137 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/936/consoleText
[repro] Revision: 5a922c3c8523cd01fae4720a57132d12c20f1191
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=TestSolrConfigHandlerConcurrent -Dtests.seed=3B43CE67D47B992B -Dtests.slow=true -Dtests.locale=ga -Dtests.timezone=America/St_Barthelemy -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestLBHttp2SolrClient -Dtests.method=testTwoServers -Dtests.seed=CE8E5C5E05AF6BA1 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=Asia/Harbin -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] TestSolrConfigHandlerConcurrent
[repro] solr/solrj
[repro] TestLBHttp2SolrClient
[repro] ant compile-test
[...truncated 3479 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSolrConfigHandlerConcurrent" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=3B43CE67D47B992B -Dtests.slow=true -Dtests.locale=ga -Dtests.timezone=America/St_Barthelemy -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 81 lines...]
[repro] ant compile-test
[...truncated 454 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestLBHttp2SolrClient" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=CE8E5C5E05AF6BA1 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=Asia/Harbin -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 86 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.client.solrj.TestLBHttp2SolrClient
[repro] 0/5 failed: org.apache.solr.handler.TestSolrConfigHandlerConcurrent
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS] Lucene-Solr-8.x-MacOSX (64bit/jdk-12.0.2) - Build # 937 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/937/
Java: 64bit/jdk-12.0.2 -XX:+UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([F03F589276385991:632410E028C502A5]:0)
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertNotNull(Assert.java:712)
at org.junit.Assert.assertNotNull(Assert.java:722)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:126)
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 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)
FAILED: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testWaitForElapsed
Error Message:
[]
Stack Trace:
java.lang.AssertionError: []
at __randomizedtesting.SeedInfo.seed([F03F589276385991:18336A83E7446B93]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testWaitForElapsed(SearchRateTriggerTest.java:263)
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 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 14562 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1008596 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-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> 1008598 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/data-dir-77-001
[junit4] 2> 1008598 WARN (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=23 numCloses=23
[junit4] 2> 1008598 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1008599 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-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> 1008599 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[F03F589276385991]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1008605 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1008606 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001
[junit4] 2> 1008606 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1008607 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1008607 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1008708 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer start zk server on port: 62842
[junit4] 2> 1008715 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:62842
[junit4] 2> 1008715 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:62842
[junit4] 2> 1008715 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 62842
[junit4] 2> 1008750 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1008782 INFO (zkConnectionManagerCallback-7937-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1008782 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1008807 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1008816 INFO (zkConnectionManagerCallback-7939-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1008816 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1008820 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1008831 INFO (zkConnectionManagerCallback-7941-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1008832 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1008969 WARN (jetty-launcher-7942-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1008969 WARN (jetty-launcher-7942-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1008969 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1008969 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1008970 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1008970 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1008970 INFO (jetty-launcher-7942-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> 1008970 INFO (jetty-launcher-7942-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> 1008975 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1008975 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1008975 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1008975 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4bb862fe{/solr,null,AVAILABLE}
[junit4] 2> 1008976 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1008976 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1008976 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1008976 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68be8b60{/solr,null,AVAILABLE}
[junit4] 2> 1008977 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52de479a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62849}
[junit4] 2> 1008977 INFO (jetty-launcher-7942-thread-1) [ ] o.e.j.s.Server Started @1009099ms
[junit4] 2> 1008977 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62849}
[junit4] 2> 1008978 ERROR (jetty-launcher-7942-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T10:01:10.532477Z
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4dbf3abd{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62850}
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-2) [ ] o.e.j.s.Server Started @1009100ms
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62850}
[junit4] 2> 1008978 ERROR (jetty-launcher-7942-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1008978 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 1008979 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1008979 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 1008979 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-03T10:01:10.533023Z
[junit4] 2> 1008980 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1008980 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1008988 INFO (zkConnectionManagerCallback-7946-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1008988 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1008989 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1008992 INFO (zkConnectionManagerCallback-7944-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1008992 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1008993 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1009236 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1009243 WARN (jetty-launcher-7942-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7a3ff60[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009243 WARN (jetty-launcher-7942-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7a3ff60[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009260 WARN (jetty-launcher-7942-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@158c689a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009260 WARN (jetty-launcher-7942-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@158c689a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009261 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62842/solr
[junit4] 2> 1009263 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1009266 INFO (zkConnectionManagerCallback-7960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1009267 INFO (jetty-launcher-7942-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1009378 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1009380 INFO (zkConnectionManagerCallback-7962-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1009381 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1009386 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1009392 WARN (jetty-launcher-7942-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1692354f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009393 WARN (jetty-launcher-7942-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1692354f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009415 WARN (jetty-launcher-7942-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@778ae1f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009415 WARN (jetty-launcher-7942-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@778ae1f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1009416 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:62842/solr
[junit4] 2> 1009420 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1009422 INFO (zkConnectionManagerCallback-7972-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1009424 INFO (jetty-launcher-7942-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1009546 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1009549 INFO (zkConnectionManagerCallback-7974-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1009549 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1009619 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:62849_solr
[junit4] 2> 1009623 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.c.Overseer Overseer (id=72153006271168520-127.0.0.1:62849_solr-n_0000000000) starting
[junit4] 2> 1009744 INFO (OverseerStateUpdate-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [n:127.0.0.1:62849_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:62849_solr
[junit4] 2> 1009751 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1009754 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62849_solr
[junit4] 2> 1009776 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1009776 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1009776 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1009793 INFO (zkCallback-7973-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1009849 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1009850 WARN (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] 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> 1009874 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:62849_solr]
[junit4] 2> 1009888 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1009889 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1009907 DEBUG (ScheduledTrigger-7979-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1009915 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1010040 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62849.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1010053 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62849.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1010053 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62849.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1010055 INFO (jetty-launcher-7942-thread-1) [n:127.0.0.1:62849_solr ] 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.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node1/.
[junit4] 2> 1010766 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1010887 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:62850_solr as DOWN
[junit4] 2> 1010892 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1010892 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:62850_solr
[junit4] 2> 1010896 INFO (zkCallback-7973-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1010897 INFO (zkCallback-7961-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1010904 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1010904 WARN (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] 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> 1010924 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1010951 DEBUG (ScheduledTrigger-7979-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1010992 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62850.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1011017 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62850.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1011018 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62850.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1011020 INFO (jetty-launcher-7942-thread-2) [n:127.0.0.1:62850_solr ] 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.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node2/.
[junit4] 2> 1011034 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1011036 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1011040 INFO (zkConnectionManagerCallback-7997-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1011040 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1011045 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1011049 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:62842/solr ready
[junit4] 2> 1011068 INFO (qtp1545187945-13132) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1011083 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1011088 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1011089 DEBUG (qtp1131994274-13130) [n:127.0.0.1:62850_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1011091 INFO (qtp1131994274-13130) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=16
[junit4] 2> 1011092 DEBUG (zkCallback-7973-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1011096 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1011098 INFO (qtp1545187945-13132) [n:127.0.0.1:62849_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1011102 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1011104 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1011115 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1011126 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1011134 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1011141 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1011148 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1011155 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1011168 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1011184 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1011215 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1011240 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1011273 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1011337 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1011364 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1011433 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1011464 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1011478 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1011504 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1011540 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1011571 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1011593 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1011616 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1011647 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1011665 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1011676 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1011685 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1011701 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1011720 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1011750 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1011760 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1011777 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1011802 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1011851 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1011877 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1011914 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1011930 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1011944 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1011962 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1011969 DEBUG (ScheduledTrigger-7979-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1011989 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1012014 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1012047 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1012119 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1012152 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1012172 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1012182 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1012193 INFO (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1012326 WARN (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection testScheduledTrigger is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1012330 DEBUG (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1012334 INFO (qtp1545187945-13135) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1012336 INFO (qtp1131994274-13129) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1012336 DEBUG (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1012340 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=3
[junit4] 2> 1012343 INFO (qtp1131994274-13131) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1012351 DEBUG (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 1455878880 sessionWrapper.createTime 1455878865765891, this.sessionWrapper.createTime 1455878865765891
[junit4] 2> 1012375 INFO (OverseerStateUpdate-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [n:127.0.0.1:62849_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62850/solr",
[junit4] 2> "node_name":"127.0.0.1:62850_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1012381 INFO (OverseerStateUpdate-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [n:127.0.0.1:62849_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62849/solr",
[junit4] 2> "node_name":"127.0.0.1:62849_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1012388 INFO (OverseerStateUpdate-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [n:127.0.0.1:62849_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:62849/solr",
[junit4] 2> "node_name":"127.0.0.1:62849_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1012593 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1012593 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1012593 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1012595 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1012626 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1012629 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1012632 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1012655 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1012657 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1012664 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1012969 DEBUG (ScheduledTrigger-7979-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1013046 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1013068 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1013104 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1013216 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n1' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1013217 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62850.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1013217 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n1/data/]
[junit4] 2> 1013278 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n2' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1013279 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62849.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1013280 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n2/data/]
[junit4] 2> 1013371 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1013371 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1013375 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1013376 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1013380 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17aa61f4[testScheduledTrigger_shard1_replica_n1] main]
[junit4] 2> 1013390 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013391 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013392 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1013399 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1013403 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1013403 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1013406 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1013406 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665663054601256960
[junit4] 2> 1013408 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n4' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1013408 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_62849.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5db02c9
[junit4] 2> 1013409 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_F03F589276385991-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n4/data/]
[junit4] 2> 1013414 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1013414 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1013416 INFO (searcherExecutor-7999-thread-1-processing-n:127.0.0.1:62850_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@17aa61f4[testScheduledTrigger_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013416 INFO (searcherExecutor-7999-thread-1-processing-n:127.0.0.1:62850_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1013416 INFO (searcherExecutor-7999-thread-1-processing-n:127.0.0.1:62850_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1013420 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1013420 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1013420 INFO (searcherExecutor-7999-thread-1-processing-n:127.0.0.1:62850_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n1] Registered new searcher Searcher@17aa61f4[testScheduledTrigger_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013423 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e1565fb[testScheduledTrigger_shard1_replica_n2] main]
[junit4] 2> 1013424 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013431 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013431 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1013441 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1013448 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1013448 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1013450 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1013450 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1013452 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1013452 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665663054649491456
[junit4] 2> 1013463 INFO (searcherExecutor-8001-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@e1565fb[testScheduledTrigger_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013463 INFO (searcherExecutor-8001-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1013463 INFO (searcherExecutor-8001-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1013464 INFO (searcherExecutor-8001-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n2] Registered new searcher Searcher@e1565fb[testScheduledTrigger_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013477 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 1013477 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1013480 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoute in=9995ms
[junit4] 2> 1013525 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1013525 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1013527 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1013527 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1013530 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fb3c51b[testScheduledTrigger_shard1_replica_n4] main]
[junit4] 2> 1013533 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013534 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1013534 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1013561 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1013562 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1013562 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1013564 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1013564 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665663054766931968
[junit4] 2> 1013567 INFO (searcherExecutor-8008-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@fb3c51b[testScheduledTrigger_shard1_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013567 INFO (searcherExecutor-8008-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1013567 INFO (searcherExecutor-8008-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1013568 INFO (searcherExecutor-8008-thread-1-processing-n:127.0.0.1:62849_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n4] Registered new searcher Searcher@fb3c51b[testScheduledTrigger_shard1_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1013573 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1013574 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1013975 DEBUG (ScheduledTrigger-7979-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1013990 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1013990 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1013990 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:62850/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1013993 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:62850/solr START replicas=[http://127.0.0.1:62849/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:62849/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1013995 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:62850/solr DONE. We have no versions. sync failed.
[junit4] 2> 1013998 INFO (qtp1545187945-13135) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 1014001 INFO (qtp1545187945-13203) [n:127.0.0.1:62849_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 1014002 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1014002 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1014002 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72153006271168518-core_node3-n_0000000000
[junit4] 2> 1014011 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:62850/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1014116 INFO (zkCallback-7961-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014127 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1014137 INFO (qtp1131994274-13133) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1544
[junit4] 2> 1014233 INFO (zkCallback-7961-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014233 INFO (zkCallback-7961-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014501 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1908
[junit4] 2> 1014590 INFO (qtp1545187945-13136) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1995
[junit4] 2> 1014591 DEBUG (OverseerThreadFactory-7981-thread-1-processing-n:127.0.0.1:62849_solr) [n:127.0.0.1:62849_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1014595 INFO (qtp1545187945-13132) [n:127.0.0.1:62849_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1014699 INFO (zkCallback-7973-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014699 INFO (zkCallback-7961-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014699 INFO (zkCallback-7973-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014699 INFO (zkCallback-7961-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1014701 INFO (qtp1545187945-13132) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=3603
[junit4] 2> 1014702 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1014708 INFO (qtp1545187945-13203) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n2:INDEX.sizeInBytes&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1014710 INFO (qtp1545187945-13128) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1014713 INFO (qtp1131994274-13130) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1014714 INFO (qtp1131994274-13129) [n:127.0.0.1:62850_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1014715 DEBUG (qtp1545187945-13135) [n:127.0.0.1:62849_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1014716 INFO (qtp1545187945-13135) [n:127.0.0.1:62849_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 1014717 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1014717 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1014717 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1014717 DEBUG (zkCallback-7973-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1014717 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] 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> 1014719 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1014720 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1014720 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1014720 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1014720 DEBUG (OverseerAutoScalingTriggerThread-72153006271168520-127.0.0.1:62849_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1014721 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b3e6924{/solr,null,AVAILABLE}
[junit4] 2> 1014722 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7a9b4271{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:62896}
[junit4] 2> 1014722 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.e.j.s.Server Started @1014844ms
[junit4] 2> 1014722 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=62896}
[junit4] 2> 1014722 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1014722 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1014722 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[F03F589276385991]) [ ] o.a.s.s.SolrDispatchFilter _
[...truncated too long message...]
er [org.apache.solr.metrics.reporters.SolrJmxReporter@3b2cf067: rootName = solr_64987, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1ba68255
[junit4] 2> 1342295 INFO (jetty-closer-9749-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44531c16: rootName = solr_64984, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@1ba68255
[junit4] 2> 1342299 INFO (closeThreadPool-9767-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153027858726925-127.0.0.1:64981_solr-n_0000000000) closing
[junit4] 2> 1342313 INFO (OverseerStateUpdate-72153027858726925-127.0.0.1:64981_solr-n_0000000000) [n:127.0.0.1:64981_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:64981_solr
[junit4] 2> 1342320 INFO (OverseerAutoScalingTriggerThread-72153027858726925-127.0.0.1:64981_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1342324 INFO (jetty-closer-9749-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1342324 INFO (jetty-closer-9749-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 1342326 INFO (zkCallback-9689-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1342327 INFO (zkCallback-9684-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1342328 INFO (zkCallback-9708-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 1342329 INFO (closeThreadPool-9767-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153027858726925-127.0.0.1:64981_solr-n_0000000000) closing
[junit4] 2> 1342333 INFO (zkCallback-9689-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1342433 INFO (jetty-closer-9749-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153027858726925-127.0.0.1:64981_solr-n_0000000000) closing
[junit4] 2> 1342436 INFO (jetty-closer-9749-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4dcf0728{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1342438 INFO (jetty-closer-9749-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2764abd8{/solr,null,UNAVAILABLE}
[junit4] 2> 1342440 INFO (zkCallback-9684-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (1)
[junit4] 2> 1342441 INFO (jetty-closer-9749-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1342452 INFO (jetty-closer-9749-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@cab3a79{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1342453 INFO (jetty-closer-9749-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3008b7f1{/solr,null,UNAVAILABLE}
[junit4] 2> 1342455 INFO (jetty-closer-9749-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1342560 INFO (jetty-closer-9749-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5fd10601{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1342561 INFO (jetty-closer-9749-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7533c414{/solr,null,UNAVAILABLE}
[junit4] 2> 1342563 INFO (jetty-closer-9749-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1342650 INFO (jetty-closer-9749-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@39e86b4e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1342652 INFO (jetty-closer-9749-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d8dca43{/solr,null,UNAVAILABLE}
[junit4] 2> 1342653 INFO (jetty-closer-9749-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1342660 INFO (TEST-SearchRateTriggerTest.testDefaultsAndBackcompat-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1342884 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> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/72153027858726925-127.0.0.1:64981_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/live_nodes
[junit4] 2> 9 /solr/overseer/queue
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 1342891 INFO (TEST-SearchRateTriggerTest.testDefaultsAndBackcompat-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:64972
[junit4] 2> 1342891 INFO (TEST-SearchRateTriggerTest.testDefaultsAndBackcompat-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64972
[junit4] 2> 1342891 INFO (TEST-SearchRateTriggerTest.testDefaultsAndBackcompat-seed#[F03F589276385991]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64972
[junit4] 2> 1342892 INFO (TEST-SearchRateTriggerTest.testDefaultsAndBackcompat-seed#[F03F589276385991]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDefaultsAndBackcompat
[junit4] 2> NOTE: leaving temporary files on disk at: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J3/temp/solr.cloud.autoscaling.SearchRateTriggerTest_F03F589276385991-001
[junit4] 2> May 03, 2020 10:06:44 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(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1554, maxMBSortInHeap=7.017756573327139, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5a1e2ff9), locale=yue, timezone=Etc/GMT-1
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 12.0.2 (64-bit)/cpus=6,threads=1,free=151235936,total=387973120
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerIntegrationTest, TestRecovery, TestSolrDeletionPolicy1, TestTextField, TestGroupingSearch, LukeRequestHandlerTest, PrimUtilsTest, TestSimpleTrackingShardHandler, TestRTGBase, TestSSLRandomization, TestNumericTerms64, TestQueryingOnDownCollection, TestCursorMarkWithoutUniqueKey, TolerantUpdateProcessorTest, TestSolrCachePerf, TestDynamicFieldCollectionResource, SolrCloudExampleTest, SolrCoreTest, IndexSizeTriggerMixedBoundsTest, TestJsonFacets, TestStressLiveNodes, TestMinHashQParser, MoveReplicaHDFSTest, TestSimUtils, TestSolrCloudWithHadoopAuthPlugin, TestReversedWildcardFilterFactory, TestClassicSimilarityFactory, HighlighterTest, MoreLikeThisComponentTest, TestExactStatsCache, TestExpandComponent, TestStressUserVersions, NoCacheHeaderTest, HDFSCollectionsAPITest, TestCloudPivotFacet, TimeZoneUtilsTest, DirectoryFactoryTest, TestFileDictionaryLookup, TriLevelCompositeIdRoutingTest, RestoreTriggerStateTest, SortSpecParsingTest, TestSort, CSVRequestHandlerTest, DocValuesTest, PathHierarchyTokenizerFactoryTest, ConfigSetsAPITest, TestDynamicLoadingUrl, PeerSyncWithBufferUpdatesTest, LeaderFailoverAfterPartitionTest, TestFiltering, DataDrivenBlockJoinTest, TestQuerySenderNoQuery, BJQParserTest, HdfsNNFailoverTest, DistanceUnitsTest, TestJmxIntegration, BlockCacheTest, SchemaVersionSpecificBehaviorTest, TestRawResponseWriter, NodeMutatorTest, V2StandaloneTest, TestPKIAuthenticationPlugin, HttpPartitionTest, SearchRateTriggerTest]
[junit4] Completed [443/907 (2!)] on J3 in 28.42s, 3 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 49597 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/937/consoleText
[repro] Revision: 5a922c3c8523cd01fae4720a57132d12c20f1191
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=F03F589276385991 -Dtests.slow=true -Dtests.locale=ki -Dtests.timezone=Africa/Banjul -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=SearchRateTriggerTest -Dtests.method=testWaitForElapsed -Dtests.seed=F03F589276385991 -Dtests.slow=true -Dtests.locale=yue -Dtests.timezone=Etc/GMT-1 -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] ScheduledTriggerIntegrationTest
[repro] SearchRateTriggerTest
[repro] ant compile-test
[...truncated 3484 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.ScheduledTriggerIntegrationTest|*.SearchRateTriggerTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=F03F589276385991 -Dtests.slow=true -Dtests.locale=ki -Dtests.timezone=Africa/Banjul -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 116 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest
[repro] Exiting with code 0
[...truncated 73 lines...]