Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/750/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger

Error Message:
ScheduledTrigger did not fire in time

Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
        at 
__randomizedtesting.SeedInfo.seed([3FB101D065519D0B:ACAA49A23BACC63F]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:566)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.base/java.lang.Thread.run(Thread.java:834)


FAILED:  
junit.framework.TestSuite.org.apache.solr.search.join.BlockJoinFacetDistribTest

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.search.join.BlockJoinFacetDistribTest:     1) Thread[id=30736, 
name=SessionTracker, state=TIMED_WAITING, group=TGRP-BlockJoinFacetDistribTest] 
        at [email protected]/java.lang.Thread.sleep(Native Method)         at 
app//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.search.join.BlockJoinFacetDistribTest: 
   1) Thread[id=30736, name=SessionTracker, state=TIMED_WAITING, 
group=TGRP-BlockJoinFacetDistribTest]
        at [email protected]/java.lang.Thread.sleep(Native Method)
        at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
        at __randomizedtesting.SeedInfo.seed([3FB101D065519D0B]:0)




Build Log:
[...truncated 15314 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
   [junit4]   2> 2117067 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-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> 2117068 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/data-dir-108-001
   [junit4]   2> 2117068 WARN  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
   [junit4]   2> 2117068 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2117069 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-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> 2117069 INFO  
(SUITE-ScheduledTriggerIntegrationTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2117071 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
   [junit4]   2> 2117071 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001
   [junit4]   2> 2117071 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2117072 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2117072 INFO  (ZkTestServer Run Thread) [     ] 
o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2117172 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkTestServer start zk server on port:65345
   [junit4]   2> 2117172 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:65345
   [junit4]   2> 2117172 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:65345
   [junit4]   2> 2117172 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 65345
   [junit4]   2> 2117174 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117178 INFO  (zkConnectionManagerCallback-8777-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117178 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117183 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117187 INFO  (zkConnectionManagerCallback-8779-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117188 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117189 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117192 INFO  (zkConnectionManagerCallback-8781-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117192 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117308 WARN  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 2117308 WARN  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
   [junit4]   2> 2117308 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 
363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.6+10
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@23672b9b{/solr,null,AVAILABLE}
   [junit4]   2> 2117310 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@61831e82{/solr,null,AVAILABLE}
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@73eeeecd{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:65352}
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.s.Server Started @1971542ms
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@4e99b554{HTTP/1.1,[http/1.1, 
h2c]}{127.0.0.1:65351}
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.s.Server Started @1971542ms
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=65352}
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=65351}
   [junit4]   2> 2117311 ERROR (jetty-launcher-8782-thread-2) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2117311 ERROR (jetty-launcher-8782-thread-1) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.6.0
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.6.0
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-16T17:04:54.539631Z
   [junit4]   2> 2117311 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-16T17:04:54.539630Z
   [junit4]   2> 2117312 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117312 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117314 INFO  (zkConnectionManagerCallback-8784-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117314 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117314 INFO  (zkConnectionManagerCallback-8786-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117314 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117315 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2117315 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2117491 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2117491 WARN  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@6e7d8c70[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117491 WARN  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@6e7d8c70[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117494 WARN  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@731e8067[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117494 WARN  (jetty-launcher-8782-thread-2) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@731e8067[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117495 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65345/solr
   [junit4]   2> 2117495 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117498 INFO  (zkConnectionManagerCallback-8793-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117498 INFO  (jetty-launcher-8782-thread-2) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117622 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2117623 WARN  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5897f56b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117623 WARN  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5897f56b[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117624 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2117627 WARN  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@90d6100[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117627 WARN  (jetty-launcher-8782-thread-1) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@90d6100[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2117627 INFO  (zkConnectionManagerCallback-8797-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117627 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 2117628 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65345/solr
   [junit4]   2> 2117630 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117633 INFO  (zkConnectionManagerCallback-8802-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117633 INFO  (jetty-launcher-8782-thread-1) [     ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117738 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 2117743 INFO  (zkConnectionManagerCallback-8806-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117743 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 2117780 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:65351_solr
   [junit4]   2> 2117782 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.Overseer Overseer 
(id=72146898448154632-127.0.0.1:65351_solr-n_0000000000) starting
   [junit4]   2> 2117802 INFO  
(OverseerStateUpdate-72146898448154632-127.0.0.1:65351_solr-n_0000000000) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.Overseer Starting to work on the main 
queue : 127.0.0.1:65351_solr
   [junit4]   2> 2117802 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:65351_solr
   [junit4]   2> 2117802 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 2117805 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with 
znode version 1
   [junit4]   2> 2117805 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 0
   [junit4]   2> 2117805 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 2117806 INFO  
(OverseerStateUpdate-72146898448154632-127.0.0.1:65351_solr-n_0000000000) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2117810 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 2117811 WARN  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_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> 2117823 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:65351_solr]
   [junit4]   2> 2117825 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 2117827 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 2117827 DEBUG (ScheduledTrigger-6425-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> 2117829 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2117850 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65351.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117852 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2117858 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65351.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117858 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65351.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117859 INFO  (jetty-launcher-8782-thread-1) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node1/.
   [junit4]   2> 2117862 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:65352_solr as DOWN
   [junit4]   2> 2117865 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2117865 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:65352_solr
   [junit4]   2> 2117867 INFO  (zkCallback-8805-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2117867 INFO  (zkCallback-8796-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2117871 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.p.PackageLoader /packages.json updated to 
version -1
   [junit4]   2> 2117872 WARN  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_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> 2117889 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 2117906 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65352.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117914 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65352.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117914 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_65352.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2117915 INFO  (jetty-launcher-8782-thread-2) 
[n:127.0.0.1:65352_solr     ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node2/.
   [junit4]   2> 2117934 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
   [junit4]   2> 2117935 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2117938 INFO  (zkConnectionManagerCallback-8817-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2117938 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2117942 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2117944 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 
127.0.0.1:65345/solr ready
   [junit4]   2> 2117953 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 2117958 INFO  (qtp1079919407-25655) [n:127.0.0.1:65351_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> 2117959 INFO  (qtp2099842840-25652) [n:127.0.0.1:65352_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> 2117959 DEBUG (qtp1079919407-25651) [n:127.0.0.1:65351_solr    
 ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 2117960 INFO  (qtp1079919407-25651) [n:127.0.0.1:65351_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling 
params={wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 2117960 DEBUG (zkCallback-8805-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 2117961 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 2117961 INFO  (qtp2099842840-25648) [n:127.0.0.1:65352_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> 2117967 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 2117970 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 2117972 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
testScheduledTrigger
   [junit4]   2> 2117975 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/managed-schema to 
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
   [junit4]   2> 2117980 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/protwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
   [junit4]   2> 2117989 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2117995 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2117999 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118003 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118010 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118016 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118020 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118029 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118034 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118039 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118046 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118048 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118055 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118060 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118065 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118069 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118074 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118079 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118083 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118087 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118093 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118099 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118102 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118107 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118116 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118121 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118125 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118130 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118135 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118138 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118142 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118145 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118148 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118151 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118155 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118158 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118161 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118164 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118168 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118171 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/solrconfig.xml to 
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
   [junit4]   2> 2118174 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/synonyms.txt to 
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
   [junit4]   2> 2118176 INFO  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_solr     ] o.a.s.c.c.ZkConfigManager Copying zk node 
/configs/_default/stopwords.txt to 
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
   [junit4]   2> 2118287 WARN  
(OverseerThreadFactory-6427-thread-1-processing-n:127.0.0.1:65351_solr) 
[n:127.0.0.1:65351_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> 2118295 INFO  
(OverseerStateUpdate-72146898448154632-127.0.0.1:65351_solr-n_0000000000) 
[n:127.0.0.1:65351_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:65352/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:65352_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2118307 INFO  
(OverseerStateUpdate-72146898448154632-127.0.0.1:65351_solr-n_0000000000) 
[n:127.0.0.1:65351_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:65351/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:65351_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2118317 INFO  
(OverseerStateUpdate-72146898448154632-127.0.0.1:65351_solr-n_0000000000) 
[n:127.0.0.1:65351_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:65352/solr";,
   [junit4]   2>   "node_name":"127.0.0.1:65352_solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2118520 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2118521 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2118521 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr    
x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2118521 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2118559 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2118559 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2118559 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2118588 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 2118588 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 2118588 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema 
name=default-config
   [junit4]   2> 2118832 DEBUG (ScheduledTrigger-6425-thread-2) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 1
   [junit4]   2> 2118882 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2118888 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2118888 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2118988 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2118988 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_65351.solr.core.testScheduledTrigger.shard1.replica_n2' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2118989 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node1/./testScheduledTrigger_shard1_replica_n2/data/]
   [junit4]   2> 2119003 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119003 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_65352.solr.core.testScheduledTrigger.shard1.replica_n1' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2119004 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n1/data/]
   [junit4]   2> 2119004 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119005 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX 
monitoring for 'solr_65352.solr.core.testScheduledTrigger.shard1.replica_n4' 
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at 
server: com.sun.jmx.mbeanserver.JmxMBeanServer@4cbd48a0
   [junit4]   2> 2119005 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_3FB101D065519D0B-001/tempDir-001/node2/./testScheduledTrigger_shard1_replica_n4/data/]
   [junit4]   2> 2119066 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119066 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119067 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119067 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119069 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119069 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2119069 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119070 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2119070 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6d1aa7cd[testScheduledTrigger_shard1_replica_n4] main]
   [junit4]   2> 2119071 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@36a7b749[testScheduledTrigger_shard1_replica_n1] main]
   [junit4]   2> 2119074 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119074 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119075 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119075 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119076 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119076 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119077 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119077 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119078 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 2119078 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 2119078 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119078 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119079 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2119079 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2119079 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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 1661341056428408832
   [junit4]   2> 2119079 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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 1661341056428408832
   [junit4]   2> 2119081 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119081 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119083 INFO  
(searcherExecutor-6438-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:65352_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to 
Searcher@6d1aa7cd[testScheduledTrigger_shard1_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119083 INFO  
(searcherExecutor-6438-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:65352_solr c:testScheduledTrigger s:shard1 
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 2119083 INFO  
(searcherExecutor-6438-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:65352_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> 2119083 INFO  
(searcherExecutor-6437-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:65352_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to 
Searcher@36a7b749[testScheduledTrigger_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119083 INFO  
(searcherExecutor-6437-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:65352_solr c:testScheduledTrigger s:shard1 
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 2119083 INFO  
(searcherExecutor-6437-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:65352_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> 2119084 INFO  
(searcherExecutor-6437-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 
r:core_node3) [n:127.0.0.1:65352_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@36a7b749[testScheduledTrigger_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119085 INFO  
(searcherExecutor-6438-thread-1-processing-n:127.0.0.1:65352_solr 
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 
r:core_node6) [n:127.0.0.1:65352_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@6d1aa7cd[testScheduledTrigger_shard1_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119085 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119085 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2119086 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@12856228[testScheduledTrigger_shard1_replica_n2] main]
   [junit4]   2> 2119090 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119091 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119091 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119092 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119093 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119093 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119094 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent 
Initializing spell checkers
   [junit4]   2> 2119094 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119095 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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}, version=1}
   [junit4]   2> 2119095 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2119095 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits 
will be reserved for 10000ms.
   [junit4]   2> 2119096 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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 1661341056446234624
   [junit4]   2> 2119102 INFO  
(searcherExecutor-6436-thread-1-processing-n:127.0.0.1:65351_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:65351_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to 
Searcher@12856228[testScheduledTrigger_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119102 INFO  
(searcherExecutor-6436-thread-1-processing-n:127.0.0.1:65351_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:65351_solr c:testScheduledTrigger s:shard1 
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] 
o.a.s.c.QuerySenderListener QuerySenderListener done.
   [junit4]   2> 2119102 INFO  
(searcherExecutor-6436-thread-1-processing-n:127.0.0.1:65351_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:65351_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> 2119102 INFO  
(searcherExecutor-6436-thread-1-processing-n:127.0.0.1:65351_solr 
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 
r:core_node5) [n:127.0.0.1:65351_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@12856228[testScheduledTrigger_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2119115 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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_node6=0, core_node3=0, core_node5=0}, version=2}
   [junit4]   2> 2119115 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_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> 2119115 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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=2 
timeoutin=9997ms
   [junit4]   2> 2119620 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119620 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119620 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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:65352/solr/testScheduledTrigger_shard1_replica_n1/
   [junit4]   2> 2119622 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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:65352/solr 
START 
replicas=[http://127.0.0.1:65351/solr/testScheduledTrigger_shard1_replica_n2/, 
http://127.0.0.1:65352/solr/testScheduledTrigger_shard1_replica_n4/] 
nUpdates=100
   [junit4]   2> 2119622 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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:65352/solr 
DONE.  We have no versions.  sync failed.
   [junit4]   2> 2119624 INFO  (qtp2099842840-25652) [n:127.0.0.1:65352_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=0
   [junit4]   2> 2119624 INFO  (qtp1079919407-25655) [n:127.0.0.1:65351_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=1
   [junit4]   2> 2119625 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119625 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119625 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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/72146898448154630-core_node3-n_0000000000
   [junit4]   2> 2119629 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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:65352/solr/testScheduledTrigger_shard1_replica_n1/ shard1
   [junit4]   2> 2119737 INFO  (zkCallback-8796-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> 2119741 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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> 2119747 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node3 
x:testScheduledTrigger_shard1_replica_n1 ] 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=1227
   [junit4]   2> 2119846 DEBUG (ScheduledTrigger-6425-thread-2) [     ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 2 and last live nodes: 2
   [junit4]   2> 2119847 INFO  (zkCallback-8796-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> 2119847 INFO  (zkCallback-8796-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> 2120120 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_solr 
c:testScheduledTrigger s:shard1 r:core_node6 
x:testScheduledTrigger_shard1_replica_n4 ] 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=1599
   [junit4]   2> 2120127 INFO  (qtp1079919407-25649) [n:127.0.0.1:65351_solr 
c:testScheduledTrigger s:shard1 r:core_node5 
x:testScheduledTrigger_shard1_replica_n2 ] 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=1606
   [junit4]   2> 2120130 INFO  (qtp2099842840-25648) [n:127.0.0.1:65352_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> 2120232 INFO  (zkCallback-8796-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> 2120232 INFO  (zkCallback-8805-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> 2120232 INFO  (zkCallback-8805-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> 2120232 INFO  (zkCallback-8796-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> 2120233 INFO  (qtp2099842840-25648) [n:127.0.0.1:65352_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=2271
   [junit4]   2> 2120234 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: 
testScheduledTrigger
   [junit4]   2> 2120240 INFO  (qtp2099842840-25650) [n:127.0.0.1:65352_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&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=1
   [junit4]   2> 2120241 INFO  (qtp2099842840-25654) [n:127.0.0.1:65352_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> 2120242 INFO  (qtp1079919407-25651) [n:127.0.0.1:65351_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}
 status=0 QTime=0
   [junit4]   2> 2120243 INFO  (qtp1079919407-25653) [n:127.0.0.1:65351_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> 2120244 DEBUG (qtp2099842840-25652) [n:127.0.0.1:65352_solr    
 ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 2120245 INFO  (qtp2099842840-25652) [n:127.0.0.1:65352_solr    
 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=8
   [junit4]   2> 2120246 DEBUG (zkCallback-8805-thread-1) [     ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 2120246 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short 
content, break even is size 23
   [junit4]   2> 2120246 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 2120246 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding 
port=0)
   [junit4]   2> 2120246 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 
1 ...
   [junit4]   2> 2120246 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 
2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 
11.0.6+10
   [junit4]   2> 2120247 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / 
nodeAdded markers
   [junit4]   2> 2120249 DEBUG 
(OverseerAutoScalingTriggerThread-72146898448154632-127.0.0.1:65351_solr-n_0000000000)
 [     ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 2120249 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2120249 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2120249 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2120249 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5fe4d91b{/solr,null,AVAILABLE}
   [junit4]   2> 2120250 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.AbstractConnector Started 
ServerConnector@486dedb4{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:65371}
   [junit4]   2> 2120250 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.s.Server Started @1974481ms
   [junit4]   2> 2120250 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=65371}
   [junit4]   2> 2120250 ERROR 
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging 
may be missing or incomplete.
   [junit4]   2> 2120250 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2120251 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? 
version 8.6.0
   [junit4]   2> 2120251 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on 
port null
   [junit4]   2> 2120251 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
   [junit4]   2> 2120251 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-03-16T17:04:57.479074Z
   [junit4]   2> 2120251 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2120254 INFO  (zkConnectionManagerCallback-8822-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2120254 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2120255 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2120440 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2120441 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@df32331[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2120441 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@df32331[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2120444 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.u.s.S.config Trusting all certificates configured for 
Client@63bb32e7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2120444 WARN  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm 
configured for Client@63bb32e7[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2120445 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:65345/solr
   [junit4]   2> 2120446 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 2120450 INFO  (zkConnectionManagerCallback-8829-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2120450 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 2120560 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 2120564 INFO  (zkConnectionManagerCallback-8831-thread-1) [    
 ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2120564 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 2120593 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2120617 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.ZkController Publish 
node=127.0.0.1:65371_solr as DOWN
   [junit4]   2> 2120625 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 2120625 INFO  
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[3FB101D065519D0B])
 [n:127.0.0.1:65371_solr     ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:65371_solr
   [junit4]   2> 2120633 INFO  (zkCallback-8816-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 2120633 INFO  (zkCallback-8805-thread-1) [     ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
 

[...truncated too long message...]

146930349572112-127.0.0.1:51825_solr-n_0000000002
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        42      /solr/live_nodes
   [junit4]   2>        38      /solr/overseer/queue
   [junit4]   2>        14      /solr/collections
   [junit4]   2>        4       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> 2642781 INFO  
(SUITE-BlockJoinFacetDistribTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:51819
   [junit4]   2> 2642781 INFO  
(SUITE-BlockJoinFacetDistribTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:51819
   [junit4]   2> 2642781 INFO  
(SUITE-BlockJoinFacetDistribTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 51819
   [junit4]   2> 2642782 INFO  
(SUITE-BlockJoinFacetDistribTest-seed#[3FB101D065519D0B]-worker) [     ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Mar 16, 2020 5:13:40 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> Mar 16, 2020 5:13:50 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.search.join.BlockJoinFacetDistribTest: 
   [junit4]   2>    1) Thread[id=30736, name=SessionTracker, 
state=TIMED_WAITING, group=TGRP-BlockJoinFacetDistribTest]
   [junit4]   2>         at [email protected]/java.lang.Thread.sleep(Native 
Method)
   [junit4]   2>         at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
   [junit4]   2> Mar 16, 2020 5:13:50 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=30736, name=SessionTracker, 
state=TIMED_WAITING, group=TGRP-BlockJoinFacetDistribTest]
   [junit4]   2> 2653184 ERROR (SessionTracker) [     ] 
o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : 
SessionTracker
   [junit4]   2>           => java.lang.InterruptedException: sleep interrupted
   [junit4]   2>        at java.base/java.lang.Thread.sleep(Native Method)
   [junit4]   2> java.lang.InterruptedException: sleep interrupted
   [junit4]   2>        at java.lang.Thread.sleep(Native Method) [?:?]
   [junit4]   2>        at 
org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) 
~[zookeeper-3.5.7.jar:3.5.7]
   [junit4]   2> Mar 16, 2020 5:13:50 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: All leaked threads terminated.
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=1, maxDocsPerChunk=5, blockShift=7), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=1, blockSize=7)), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@38dae96),
 locale=sr-CS, timezone=America/North_Dakota/New_Salem
   [junit4]   2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 
(64-bit)/cpus=6,threads=1,free=157322704,total=387973120
   [junit4]   2> NOTE: All tests run in this JVM: [MoveReplicaHDFSFailoverTest, 
TestAtomicUpdateErrorCases, DistribCursorPagingTest, ClusterStateUpdateTest, 
Tagger2Test, NodeAddedTriggerIntegrationTest, TestCloudSearcherWarming, 
RestoreTriggerStateTest, FullHLLTest, SearchRateTriggerTest, 
TestTolerantUpdateProcessorRandomCloud, ExitableDirectoryReaderTest, 
TestSolr4Spatial, TestInPlaceUpdatesRequiredField, TriggerIntegrationTest, 
TestExactStatsCache, RuleEngineTest, LargeFieldTest, 
JWTVerificationkeyResolverTest, TestClassicSimilarityFactory, 
TestLMJelinekMercerSimilarityFactory, TestSubQueryTransformerCrossCore, 
TestDynamicURP, HDFSCollectionsAPITest, TestFieldCacheSortRandom, 
TestSolrQueryResponse, TestCustomSort, PathHierarchyTokenizerFactoryTest, 
BadComponentTest, TestManagedSynonymFilterFactory, 
OverseerModifyCollectionTest, ZkControllerTest, 
DistributedQueryComponentOptimizationTest, TestCursorMarkWithoutUniqueKey, 
TestLogWatcher, TestApiFramework, TestPKIAuthenticationPlugin, TestDocTermOrds, 
TestPullReplicaErrorHandling, TestReloadAndDeleteDocs, 
DistributedFacetSimpleRefinementLongTailTest, SimpleCollectionCreateDeleteTest, 
HttpPartitionOnCommitTest, TestCryptoKeys, BasicAuthOnSingleNodeTest, 
TestPHPSerializedResponseWriter, TestSimUtils, TestClusterStateMutator, 
ResponseBuilderTest, TestReqParamsAPI, NestedShardedAtomicUpdateTest, 
TestStressRecovery, AddReplicaTest, TestSkipOverseerOperations, 
TestSolrDeletionPolicy2, CollectionsAPIDistributedZkTest, 
SolrSlf4jReporterTest, TestCollationField, HdfsNNFailoverTest, 
TestCSVResponseWriter, OpenExchangeRatesOrgProviderTest, BlockCacheTest, 
RecoveryZkTest, AtomicUpdateProcessorFactoryTest, SolrCLIZkUtilsTest, 
RequestLoggingTest, TestCloudNestedDocsSort, TestReloadDeadlock, 
TermsComponentTest, DataDrivenBlockJoinTest, TestLegacyTerms, 
CoreAdminHandlerTest, TestSchemaSimilarityResource, 
TestBackupRepositoryFactory, TestNoOpRegenerator, HighlighterConfigTest, 
TestConfigSetImmutable, RequiredFieldsTest, FacetPivotSmallTest, 
StatsComponentTest, TestCorePropertiesReload, TestSolrConfigHandlerConcurrent, 
SynonymTokenizerTest, SystemCollectionCompatTest, OutputWriterTest, 
HdfsThreadLeakTest, TestIntervalFaceting, TestFunctionQuery, 
AnalysisAfterCoreReloadTest, BasicFunctionalityTest, ConvertedLegacyTest, 
CursorPagingTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, 
TestDistributedSearch, ChaosMonkeySafeLeaderTest, CollectionsAPISolrJTest, 
ReplicationFactorTest, TestRandomRequestDistribution, 
TestSolrCloudWithKerberosAlt, TestSolrCloudWithSecureImpersonation, 
TestTlogReplayVsRecovery, TestWithCollection, 
HdfsAutoAddReplicasIntegrationTest, HttpTriggerListenerTest, 
IndexSizeTriggerSizeEstimationTest, TestSimLargeCluster, 
DistributedFacetExistsSmallTest, DistributedFacetPivotSmallTest, 
TestExportWriter, HighlighterWithoutStoredIdTest, 
TestUnifiedSolrHighlighterWithoutStoredId, SolrCoreMetricManagerTest, 
RegexBytesRefFilterTest, TestUnInvertedFieldException, TestWriterPerf, 
TestMacroExpander, TestMacros, JSONWriterTest, TestGeoJSONResponseWriter, 
TestRawResponseWriter, TestManagedResource, TestManagedResourceStorage, 
TestCopyFieldCollectionResource, TestDynamicFieldCollectionResource, 
TestFieldCollectionResource, TestFieldTypeCollectionResource, 
BadIndexSchemaTest, CurrencyFieldTypeTest, EnumFieldTest, IndexSchemaTest, 
TestManagedSchemaAPI, TestManagedSchemaThreadSafety, TestSchemaManager, 
TestSortableTextField, TestUseDocValuesAsStored2, QueryEqualityTest, 
QueryParsingTest, RankQueryTest, ReturnFieldsTest, 
SignificantTermsQParserPluginTest, SortSpecParsingTest, SpatialFilterTest, 
TestAddFieldRealTimeGet, TestCaffeineCache, TestGraphTermsQParserPlugin, 
TestIndexSearcher, TestPseudoReturnFields, TestQueryUtils, TestRangeQuery, 
TestSurroundQueryParser, TestValueSourceCache, TestJsonFacetsStatsParsing, 
TestOrdValues, TestSortByMinMaxFunction, DistanceFunctionTest, BJQParserTest, 
BlockJoinFacetDistribTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=BlockJoinFacetDistribTest -Dtests.seed=3FB101D065519D0B 
-Dtests.slow=true -Dtests.locale=sr-CS 
-Dtests.timezone=America/North_Dakota/New_Salem -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.00s J3 | BlockJoinFacetDistribTest (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.search.join.BlockJoinFacetDistribTest: 
   [junit4]    >    1) Thread[id=30736, name=SessionTracker, 
state=TIMED_WAITING, group=TGRP-BlockJoinFacetDistribTest]
   [junit4]    >         at [email protected]/java.lang.Thread.sleep(Native 
Method)
   [junit4]    >         at 
app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3FB101D065519D0B]:0)
   [junit4] Completed [779/901 (2!)] on J3 in 49.65s, 1 test, 1 error <<< 
FAILURES!

[...truncated 48354 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/750/consoleText

[repro] Revision: 33cd6909df28d39eedf2f777082ba26cf20a59ba

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line:  ant test  -Dtestcase=ScheduledTriggerIntegrationTest 
-Dtests.method=testScheduledTrigger -Dtests.seed=3FB101D065519D0B 
-Dtests.slow=true -Dtests.locale=dyo-SN -Dtests.timezone=Europe/Busingen 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1

[repro] Repro line:  ant test  -Dtestcase=BlockJoinFacetDistribTest 
-Dtests.seed=3FB101D065519D0B -Dtests.slow=true -Dtests.locale=sr-CS 
-Dtests.timezone=America/North_Dakota/New_Salem -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       ScheduledTriggerIntegrationTest
[repro]       BlockJoinFacetDistribTest
[repro] ant compile-test

[...truncated 3448 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 
-Dtests.class="*.ScheduledTriggerIntegrationTest|*.BlockJoinFacetDistribTest" 
-Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" 
-Dtests.seed=3FB101D065519D0B -Dtests.slow=true -Dtests.locale=dyo-SN 
-Dtests.timezone=Europe/Busingen -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[...truncated 101 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: 
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro]   0/5 failed: org.apache.solr.search.join.BlockJoinFacetDistribTest
[repro] Exiting with code 0

[...truncated 73 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to