Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/753/

3 tests failed.
FAILED:  
org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud

Error Message:
Could not find collection : legacyFalse

Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : legacyFalse
        at 
__randomizedtesting.SeedInfo.seed([4357E02582176203:925012A02618E931]:0)
        at 
org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
        at 
org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:258)
        at 
org.apache.solr.cloud.LegacyCloudClusterPropTest.checkMandatoryProps(LegacyCloudClusterPropTest.java:154)
        at 
org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:91)
        at 
org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:71)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance

Error Message:
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
        at 
__randomizedtesting.SeedInfo.seed([4357E02582176203:52E3BAC23B9E76F5]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.autoscaling.NodeLostTriggerTest.testListenerAcceptance(NodeLostTriggerTest.java:265)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.autoscaling.SystemLogListenerTest.test

Error Message:
Trigger was not fired 

Stack Trace:
java.lang.AssertionError: Trigger was not fired 
        at 
__randomizedtesting.SeedInfo.seed([4357E02582176203:CB03DFFF2CEB0FFB]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.autoscaling.SystemLogListenerTest.test(SystemLogListenerTest.java:151)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12864 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.SystemLogListenerTest
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/init-core-data-001
   [junit4]   2> 1205781 WARN  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=50 numCloses=50
   [junit4]   2> 1205781 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1205783 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1205783 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1205783 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001
   [junit4]   2> 1205783 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1205793 INFO  (Thread-558) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 1205793 INFO  (Thread-558) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 1205795 ERROR (Thread-558) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1205885 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:40996
   [junit4]   2> 1205887 INFO  (zkConnectionManagerCallback-3356-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1205890 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 1205890 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 1205890 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6149381{/solr,null,AVAILABLE}
   [junit4]   2> 1205892 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@57a7471a{/solr,null,AVAILABLE}
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@6aacaf38{HTTP/1.1,[http/1.1]}{127.0.0.1:44020}
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.e.j.s.Server Started @1205999ms
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=44020}
   [junit4]   2> 1205902 ERROR (jetty-launcher-3353-thread-3) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.5.0
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1205902 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-08-08T23:05:45.515Z
   [junit4]   2> 1205903 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@23ea5d33{HTTP/1.1,[http/1.1]}{127.0.0.1:35806}
   [junit4]   2> 1205903 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.e.j.s.Server Started @1206000ms
   [junit4]   2> 1205903 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=35806}
   [junit4]   2> 1205903 ERROR (jetty-launcher-3353-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1205903 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1205904 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.5.0
   [junit4]   2> 1205904 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1205904 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1205904 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-08-08T23:05:45.517Z
   [junit4]   2> 1205905 INFO  (zkConnectionManagerCallback-3360-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1205905 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5b21351d{/solr,null,AVAILABLE}
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@76027614{HTTP/1.1,[http/1.1]}{127.0.0.1:43109}
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.e.j.s.Server Started @1206003ms
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=43109}
   [junit4]   2> 1205906 ERROR (jetty-launcher-3353-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.5.0
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1205906 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-08-08T23:05:45.519Z
   [junit4]   2> 1205907 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1205912 INFO  (zkConnectionManagerCallback-3358-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1205912 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1205916 INFO  (zkConnectionManagerCallback-3362-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1205917 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1205980 INFO  (jetty-launcher-3353-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40996/solr
   [junit4]   2> 1205981 INFO  (zkConnectionManagerCallback-3366-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1205988 INFO  (zkConnectionManagerCallback-3368-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206040 INFO  (jetty-launcher-3353-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40996/solr
   [junit4]   2> 1206042 INFO  (zkConnectionManagerCallback-3374-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206045 INFO  (zkConnectionManagerCallback-3376-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206058 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:43109_solr
   [junit4]   2> 1206060 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.Overseer Overseer 
(id=73113178873528327-127.0.0.1:43109_solr-n_0000000000) starting
   [junit4]   2> 1206061 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:35806_solr
   [junit4]   2> 1206070 INFO  (zkCallback-3367-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1206071 INFO  (zkCallback-3375-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1206072 INFO  (jetty-launcher-3353-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40996/solr
   [junit4]   2> 1206073 INFO  (zkConnectionManagerCallback-3382-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206076 INFO  (zkConnectionManagerCallback-3387-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206076 INFO  (zkConnectionManagerCallback-3389-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206077 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 1206078 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:40996/solr ready
   [junit4]   2> 1206080 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43109_solr
   [junit4]   2> 1206081 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1206081 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:43109_solr
   [junit4]   2> 1206082 INFO  (zkCallback-3367-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1206084 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 1206086 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1206086 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and 
.scheduled_maintenance triggers
   [junit4]   2> 1206087 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode 
version 1
   [junit4]   2> 1206088 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1206088 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44020_solr
   [junit4]   2> 1206088 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion -1
   [junit4]   2> 1206088 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 1
   [junit4]   2> 1206093 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1206094 INFO  (zkCallback-3367-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1206095 INFO  (zkCallback-3386-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1206095 INFO  (zkCallback-3388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1206098 INFO  (zkConnectionManagerCallback-3396-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206099 INFO  (zkCallback-3375-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1206099 INFO  (zkCallback-3386-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1206100 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1206101 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:40996/solr ready
   [junit4]   2> 1206101 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1206101 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial 
livenodes: [127.0.0.1:44020_solr, 127.0.0.1:35806_solr, 127.0.0.1:43109_solr]
   [junit4]   2> 1206106 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1206107 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, 
lastZnodeVersion 1
   [junit4]   2> 1206107 DEBUG (ScheduledTrigger-1931-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1206108 INFO  (zkConnectionManagerCallback-3403-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206109 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 1206110 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:40996/solr ready
   [junit4]   2> 1206110 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system 
collection, keeping metrics history in memory.
   [junit4]   2> 1206123 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206138 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206149 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206150 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206153 INFO  (jetty-launcher-3353-thread-3) 
[n:127.0.0.1:44020_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/.
   [junit4]   2> 1206153 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206153 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206161 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206162 INFO  (jetty-launcher-3353-thread-1) 
[n:127.0.0.1:35806_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/.
   [junit4]   2> 1206167 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206167 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1206168 INFO  (jetty-launcher-3353-thread-2) 
[n:127.0.0.1:43109_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/.
   [junit4]   2> 1206236 INFO  (zkConnectionManagerCallback-3406-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206239 INFO  (zkConnectionManagerCallback-3411-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1206240 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 1206240 INFO  
(SUITE-SystemLogListenerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40996/solr ready
   [junit4]   2> 1206245 INFO  (qtp2072392395-8014) [n:127.0.0.1:35806_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
name=.system&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2 and 
sendToOCPQueue=true
   [junit4]   2> 1206251 INFO  (OverseerThreadFactory-1935-thread-1) [    ] 
o.a.s.c.a.c.CreateCollectionCmd Create collection .system
   [junit4]   2> 1206362 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43109/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1206364 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:44020/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1206366 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":".system",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":".system_shard1_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:35806/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1206570 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr    
x:.system_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1206572 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr    
x:.system_shard1_replica_n4] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1206572 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr    
x:.system_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1206572 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr    
x:.system_shard1_replica_n4] o.a.s.c.TransientSolrCoreCacheDefault Allocating 
transient cache for 2147483647 transient cores
   [junit4]   2> 1207109 DEBUG (ScheduledTrigger-1931-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1207586 WARN  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.Config You 
should not use LATEST as luceneMatchVersion property: if you use this setting, 
and then Solr upgrades to a newer release of Lucene, sizable changes may 
happen. If precise back compatibility is important then you should instead 
explicitly specify an actual Lucene version.
   [junit4]   2> 1207587 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 1207588 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 1207613 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 1207618 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 1207618 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 1207618 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but 
managed schema resource managed-schema not found - loading non-managed schema 
schema.xml instead
   [junit4]   2> 1207620 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.IndexSchema [.system_shard1_replica_n4] Schema name=_system collection 
or core
   [junit4]   2> 1207620 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection 
or core
   [junit4]   2> 1207624 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 1207624 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 1207625 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.IndexSchema [.system_shard1_replica_n1] Schema name=_system collection 
or core
   [junit4]   2> 1207629 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 1207629 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at 
/configs/.system/managed-schema
   [junit4]   2> 1207632 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to 
/configs/.system/schema.xml.bak
   [junit4]   2> 1207632 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using 
configuration from collection .system, trusted=true
   [junit4]   2> 1207632 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.core..system.shard1.replica_n1' (registry 
'solr.core..system.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1207632 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1207632 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.SolrCore 
[[.system_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/.system_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/./.system_shard1_replica_n1/data/]
   [junit4]   2> 1207642 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 1207647 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ManagedIndexSchema Managed schema znode at 
/configs/.system/managed-schema already exists - no need to create it
   [junit4]   2> 1207647 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, the non-managed schema /configs/.system/schema.xml no longer exists.
   [junit4]   2> 1207647 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n4' using 
configuration from collection .system, trusted=true
   [junit4]   2> 1207648 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.core..system.shard1.replica_n4' (registry 
'solr.core..system.shard1.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1207648 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1207648 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.c.SolrCore 
[[.system_shard1_replica_n4] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/.system_shard1_replica_n4],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/./.system_shard1_replica_n4/data/]
   [junit4]   2> 1207650 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in 
ZooKeeper, the non-managed schema /configs/.system/schema.xml no longer exists.
   [junit4]   2> 1207650 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n2' using 
configuration from collection .system, trusted=true
   [junit4]   2> 1207650 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.core..system.shard1.replica_n2' (registry 
'solr.core..system.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1207650 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1207650 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.SolrCore 
[[.system_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/.system_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/./.system_shard1_replica_n2/data/]
   [junit4]   2> 1207733 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1207733 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1207734 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs 
   [junit4]   2> 1207734 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1207737 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e34d5d7[.system_shard1_replica_n2] 
main]
   [junit4]   2> 1207738 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 1207740 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 1207740 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 1207741 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1207741 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1207742 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs 
   [junit4]   2> 1207742 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1207743 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@64d625b4[.system_shard1_replica_n4] 
main]
   [junit4]   2> 1207744 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 1207746 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 1207746 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 1207746 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 1207747 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ZkIndexSchemaReader Retrieved schema version 0 from ZooKeeper
   [junit4]   2> 1207749 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.IndexSchema [.system_shard1_replica_n4] Schema name=_system collection 
or core
   [junit4]   2> 1207749 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.IndexSchema [.system_shard1_replica_n2] Schema name=_system collection 
or core
   [junit4]   2> 1207797 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 1207797 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 53 ms
   [junit4]   2> 1207798 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1207799 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid 
field id
   [junit4]   2> 1207799 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 51 ms
   [junit4]   2> 1207799 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1207805 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1608273936953376768
   [junit4]   2> 1207805 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1207805 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1207806 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommited docs 
   [junit4]   2> 1207806 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1207808 INFO  
(searcherExecutor-1946-thread-1-processing-n:127.0.0.1:35806_solr 
x:.system_shard1_replica_n4 c:.system s:shard1 r:core_node6) 
[n:127.0.0.1:35806_solr c:.system s:shard1 r:core_node6 
x:.system_shard1_replica_n4] o.a.s.c.SolrCore [.system_shard1_replica_n4] 
Registered new searcher Searcher@64d625b4[.system_shard1_replica_n4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1207809 INFO  
(searcherExecutor-1947-thread-1-processing-n:127.0.0.1:44020_solr 
x:.system_shard1_replica_n2 c:.system s:shard1 r:core_node5) 
[n:127.0.0.1:44020_solr c:.system s:shard1 r:core_node5 
x:.system_shard1_replica_n2] o.a.s.c.SolrCore [.system_shard1_replica_n2] 
Registered new searcher Searcher@e34d5d7[.system_shard1_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1207809 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1608273936957571072
   [junit4]   2> 1207811 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@57ec51fb[.system_shard1_replica_n1] 
main]
   [junit4]   2> 1207813 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/.system
   [junit4]   2> 1207814 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node6=0}, version=0}
   [junit4]   2> 1207814 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/.system
   [junit4]   2> 1207814 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at 
/configs/.system/managed-schema
   [junit4]   2> 1207816 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
   [junit4]   2> 1207817 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1207817 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.u.UpdateLog 
Could not find max version in index or recent updates, using new clock 
1608273936965959680
   [junit4]   2> 1207818 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node6=0, core_node5=0}, 
version=1}
   [junit4]   2> 1207821 INFO  
(searcherExecutor-1945-thread-1-processing-n:127.0.0.1:43109_solr 
x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node3) 
[n:127.0.0.1:43109_solr c:.system s:shard1 r:core_node3 
x:.system_shard1_replica_n1] o.a.s.c.SolrCore [.system_shard1_replica_n1] 
Registered new searcher Searcher@57ec51fb[.system_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1207821 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard shard1: total=3 found=2 timeoutin=9998ms
   [junit4]   2> 1207823 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/.system/terms/shard1 to Terms{values={core_node6=0, core_node3=0, 
core_node5=0}, version=2}
   [junit4]   2> 1208109 DEBUG (ScheduledTrigger-1931-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1208322 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 1208322 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 1208322 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.SyncStrategy Sync replicas to 
http://127.0.0.1:35806/solr/.system_shard1_replica_n4/
   [junit4]   2> 1208323 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n4 url=http://127.0.0.1:35806/solr START 
replicas=[http://127.0.0.1:43109/solr/.system_shard1_replica_n1/, 
http://127.0.0.1:44020/solr/.system_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 1208324 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] o.a.s.u.PeerSync 
PeerSync: core=.system_shard1_replica_n4 url=http://127.0.0.1:35806/solr DONE.  
We have no versions.  sync failed.
   [junit4]   2> 1208332 INFO  (qtp828743457-8005) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] o.a.s.c.S.Request 
[.system_shard1_replica_n1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=5
   [junit4]   2> 1208334 INFO  (qtp1826014555-8003) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] o.a.s.c.S.Request 
[.system_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> 1208334 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the 
next candidate
   [junit4]   2> 1208334 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
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> 1208334 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 1208337 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
http://127.0.0.1:35806/solr/.system_shard1_replica_n4/ shard1
   [junit4]   2> 1208439 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 1208442 INFO  (qtp2072392395-8002) [n:127.0.0.1:35806_solr 
c:.system s:shard1 r:core_node6 x:.system_shard1_replica_n4] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n4&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1870
   [junit4]   2> 1208542 INFO  (zkCallback-3367-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 1208824 INFO  (qtp1826014555-8018) [n:127.0.0.1:44020_solr 
c:.system s:shard1 r:core_node5 x:.system_shard1_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n2&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2252
   [junit4]   2> 1208827 INFO  (qtp828743457-8020) [n:127.0.0.1:43109_solr 
c:.system s:shard1 r:core_node3 x:.system_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=2258
   [junit4]   2> 1208830 INFO  (qtp2072392395-8014) [n:127.0.0.1:35806_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 1208928 INFO  (zkCallback-3388-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 1208930 INFO  (zkCallback-3367-thread-1) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 1208930 INFO  (zkCallback-3375-thread-2) [    ] 
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent 
state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] 
for collection [.system] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 1209109 DEBUG (ScheduledTrigger-1931-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1209830 INFO  (qtp2072392395-8014) [n:127.0.0.1:35806_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={name=.system&nrtReplicas=3&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=3585
   [junit4]   2> 1209859 INFO  
(TEST-SystemLogListenerTest.test-seed#[4357E02582176203]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1209888 INFO  (qtp2072392395-8004) [n:127.0.0.1:35806_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209889 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_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> 1209890 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209891 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_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> 1209895 INFO  (qtp1826014555-8007) [n:127.0.0.1:44020_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209901 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_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> 1209902 DEBUG (qtp828743457-8008) [n:127.0.0.1:43109_solr    ] 
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1209902 INFO  (qtp828743457-8008) [n:127.0.0.1:43109_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=39
   [junit4]   2> 1209902 DEBUG (zkCallback-3386-thread-2) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
2
   [junit4]   2> 1209903 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 2
   [junit4]   2> 1209905 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger node_lost_trigger - Initial 
livenodes: [127.0.0.1:44020_solr, 127.0.0.1:35806_solr, 127.0.0.1:43109_solr]
   [junit4]   2> 1209906 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1209906 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, 
lastZnodeVersion 2
   [junit4]   2> 1209906 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n4:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209907 DEBUG (ScheduledTrigger-1931-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with 
currently live nodes: 3
   [junit4]   2> 1209907 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_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> 1209909 INFO  (qtp828743457-8025) [n:127.0.0.1:43109_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n1:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209910 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_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> 1209911 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics 
params={wt=javabin&version=2&key=solr.core..system.shard1.replica_n2:INDEX.sizeInBytes}
 status=0 QTime=0
   [junit4]   2> 1209912 INFO  (qtp1826014555-8003) [n:127.0.0.1:44020_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> 1209912 DEBUG (qtp1826014555-8017) [n:127.0.0.1:44020_solr    
] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
   [junit4]   2> 1209912 INFO  (qtp1826014555-8017) [n:127.0.0.1:44020_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling 
params={wt=javabin&version=2} status=0 QTime=8
   [junit4]   2> 1209913 DEBUG (zkCallback-3386-thread-2) [    ] 
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 
3
   [junit4]   2> 1209913 INFO  (qtp2072392395-8019) [n:127.0.0.1:35806_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
collection.configName=conf&maxShardsPerNode=3&name=test&nrtReplicas=2&action=CREATE&numShards=3&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 1209913 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto 
znodeVersion 3
   [junit4]   2> 1209916 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
   [junit4]   2> 1209916 DEBUG 
(OverseerAutoScalingTriggerThread-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, 
lastZnodeVersion 3
   [junit4]   2> 1209917 INFO  (OverseerThreadFactory-1935-thread-2) [    ] 
o.a.s.c.a.c.CreateCollectionCmd Create collection test
   [junit4]   2> 1209917 INFO  
(OverseerCollectionConfigSetProcessor-73113178873528327-127.0.0.1:43109_solr-n_0000000000)
 [    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 1210110 DEBUG (ScheduledTrigger-1931-thread-1) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1210124 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"test_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43109/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210127 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"test_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:35806/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210128 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"test_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:44020/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210131 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"test_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:43109/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210133 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"test_shard3_replica_n8",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:35806/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210135 INFO  
(OverseerStateUpdate-73113178873528327-127.0.0.1:43109_solr-n_0000000000) [    
] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"test",
   [junit4]   2>   "shard":"shard3",
   [junit4]   2>   "core":"test_shard3_replica_n10",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"http://127.0.0.1:44020/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 1210336 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr    
x:test_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=test_shard1_replica_n2&action=CREATE&numShards=3&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210336 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr    
x:test_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=test_shard1_replica_n1&action=CREATE&numShards=3&collection=test&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210337 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr    
x:test_shard2_replica_n4] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=test_shard2_replica_n4&action=CREATE&numShards=3&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210347 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr    
x:test_shard3_replica_n8] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=test_shard3_replica_n8&action=CREATE&numShards=3&collection=test&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210347 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr    
x:test_shard3_replica_n10] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=test_shard3_replica_n10&action=CREATE&numShards=3&collection=test&shard=shard3&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210348 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr    
x:test_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=test_shard2_replica_n6&action=CREATE&numShards=3&collection=test&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 1210911 DEBUG (ScheduledTrigger-1931-thread-4) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger with 
currently live nodes: 3
   [junit4]   2> 1211110 DEBUG (ScheduledTrigger-1931-thread-2) [    ] 
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with 
currently live nodes: 3
   [junit4]   2> 1211350 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211351 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211357 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.s.IndexSchema 
[test_shard2_replica_n4] Schema name=minimal
   [junit4]   2> 1211357 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.s.IndexSchema 
[test_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 1211360 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211360 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard1_replica_n2' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211360 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211360 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard2_replica_n4' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211361 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.core.test.shard1.replica_n2' (registry 
'solr.core.test.shard1.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211361 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.core.test.shard2.replica_n4' (registry 
'solr.core.test.shard2.replica_n4') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211361 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211361 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211361 INFO  (qtp2072392395-8023) [n:127.0.0.1:35806_solr 
c:test s:shard1 r:core_node5 x:test_shard1_replica_n2] o.a.s.c.SolrCore 
[[test_shard1_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/test_shard1_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/./test_shard1_replica_n2/data/]
   [junit4]   2> 1211361 INFO  (qtp1826014555-8021) [n:127.0.0.1:44020_solr 
c:test s:shard2 r:core_node7 x:test_shard2_replica_n4] o.a.s.c.SolrCore 
[[test_shard2_replica_n4] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/test_shard2_replica_n4],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/./test_shard2_replica_n4/data/]
   [junit4]   2> 1211370 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211376 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211411 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.s.IndexSchema 
[test_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 1211411 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.c.SolrConfig Using 
Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211413 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211413 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard1_replica_n1' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211415 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.5.0
   [junit4]   2> 1211420 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.core.test.shard1.replica_n1' (registry 
'solr.core.test.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211420 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211421 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.c.SolrCore 
[[test_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/test_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/./test_shard1_replica_n1/data/]
   [junit4]   2> 1211422 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.s.IndexSchema 
[test_shard3_replica_n10] Schema name=minimal
   [junit4]   2> 1211423 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.s.IndexSchema 
[test_shard3_replica_n8] Schema name=minimal
   [junit4]   2> 1211424 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.s.IndexSchema 
[test_shard2_replica_n6] Schema name=minimal
   [junit4]   2> 1211424 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211424 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard3_replica_n10' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211425 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_44020.solr.core.test.shard3.replica_n10' (registry 
'solr.core.test.shard3.replica_n10') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211425 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211425 INFO  (qtp1826014555-8022) [n:127.0.0.1:44020_solr 
c:test s:shard3 r:core_node12 x:test_shard3_replica_n10] o.a.s.c.SolrCore 
[[test_shard3_replica_n10] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/test_shard3_replica_n10],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node3/./test_shard3_replica_n10/data/]
   [junit4]   2> 1211425 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211425 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard3_replica_n8' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211425 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_35806.solr.core.test.shard3.replica_n8' (registry 
'solr.core.test.shard3.replica_n8') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211426 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211426 INFO  (qtp2072392395-8012) [n:127.0.0.1:35806_solr 
c:test s:shard3 r:core_node11 x:test_shard3_replica_n8] o.a.s.c.SolrCore 
[[test_shard3_replica_n8] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/test_shard3_replica_n8],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node1/./test_shard3_replica_n8/data/]
   [junit4]   2> 1211427 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.s.IndexSchema 
Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 1211427 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.c.CoreContainer 
Creating SolrCore 'test_shard2_replica_n6' using configuration from collection 
test, trusted=true
   [junit4]   2> 1211427 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_43109.solr.core.test.shard2.replica_n6' (registry 
'solr.core.test.shard2.replica_n6') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7e2aa632
   [junit4]   2> 1211428 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 1211428 INFO  (qtp828743457-8016) [n:127.0.0.1:43109_solr 
c:test s:shard2 r:core_node9 x:test_shard2_replica_n6] o.a.s.c.SolrCore 
[[test_shard2_replica_n6] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/test_shard2_replica_n6],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.cloud.autoscaling.SystemLogListenerTest_4357E02582176203-001/tempDir-001/node2/./test_shard2_replica_n6/data/]
   [junit4]   2> 1211510 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.u.UpdateHandler 
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 1211510 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.u.UpdateLog 
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1211511 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.u.CommitTracker 
Hard AutoCommit: disabled
   [junit4]   2> 1211511 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] o.a.s.u.CommitTracker 
Soft AutoCommit: disabled
   [junit4]   2> 1211513 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b801f1f[test_shard1_replica_n1] 
main]
   [junit4]   2> 1211517 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 1211517 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1 r:core_node3 x:test_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 1211518 INFO  (qtp828743457-8024) [n:127.0.0.1:43109_solr 
c:test s:shard1

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

-> (1)
   [junit4]   2> 3527849 INFO  (zkCallback-6324-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 3527849 INFO  (zkCallback-6393-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 3527850 INFO  (jetty-closer-6261-thread-3) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44392_solr
   [junit4]   2> 3527874 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x103c02f94930011, likely client has closed socket
   [junit4]   2> 3527891 INFO  (jetty-closer-6261-thread-3) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 3527891 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x103c02f94930023, likely client has closed socket
   [junit4]   2> 3527892 INFO  (zkCallback-6332-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 3527892 INFO  (zkCallback-6300-thread-2) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 3527898 WARN  (zkCallback-6393-thread-2) [    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 3527900 INFO  (jetty-closer-6261-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@48fc059a{/solr,null,UNAVAILABLE}
   [junit4]   2> 3527900 INFO  (zkCallback-6300-thread-1) [    ] 
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44392_solr
   [junit4]   2> 3527901 INFO  (jetty-closer-6261-thread-1) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 3527912 INFO  (jetty-closer-6261-thread-3) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@527b3fad{/solr,null,UNAVAILABLE}
   [junit4]   2> 3527932 INFO  (jetty-closer-6261-thread-3) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 3527936 INFO  (jetty-closer-6261-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@368a0a9e{/solr,null,UNAVAILABLE}
   [junit4]   2> 3527938 INFO  (jetty-closer-6261-thread-2) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 3527939 ERROR 
(SUITE-NodeLostTriggerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 3527939 INFO  
(SUITE-NodeLostTriggerTest-seed#[4357E02582176203]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:42265 42265
   [junit4]   2> 3528050 INFO  (Thread-4450) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:42265 42265
   [junit4]   2> 3528052 WARN  (Thread-4450) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        17      /solr/aliases.json
   [junit4]   2>        17      /solr/clusterprops.json
   [junit4]   2>        8       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        17      /solr/clusterstate.json
   [junit4]   2>        2       
/solr/overseer_elect/election/73113329556717575-127.0.0.1:42120_solr-n_0000000004
   [junit4]   2>        2       /solr/autoscaling.json
   [junit4]   2>        2       
/solr/overseer_elect/election/73113329556717581-127.0.0.1:44392_solr-n_0000000002
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        17      /solr/collections
   [junit4]   2>        16      /solr/live_nodes
   [junit4]   2>        2       /solr/overseer/queue
   [junit4]   2>        2       /solr/autoscaling/events/.scheduled_maintenance
   [junit4]   2>        2       /solr/autoscaling/events/.auto_add_replicas
   [junit4]   2>        2       /solr/overseer/collection-queue-work
   [junit4]   2>        2       /solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.NodeLostTriggerTest_4357E02582176203-001
   [junit4]   2> Aug 08, 2018 11:44:27 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=17, maxMBSortInHeap=7.128496689680402, 
sim=RandomSimilarity(queryNorm=true): {}, locale=es-EC, 
timezone=America/Louisville
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=168221480,total=524288000
   [junit4]   2> NOTE: All tests run in this JVM: 
[AddSchemaFieldsUpdateProcessorFactoryTest, SolrSlf4jReporterTest, 
CoreMergeIndexesAdminHandlerTest, MetricTriggerIntegrationTest, 
TestTolerantUpdateProcessorCloud, SuggestComponentContextFilterQueryTest, 
MetricsConfigTest, TestGraphTermsQParserPlugin, CSVRequestHandlerTest, 
ChangedSchemaMergeTest, TestNonDefinedSimilarityFactory, TestJettySolrRunner, 
TestSubQueryTransformerDistrib, TestRandomCollapseQParserPlugin, 
TimeZoneUtilsTest, HLLSerializationTest, ConnectionManagerTest, 
DeleteInactiveReplicaTest, TestJsonFacetsStatsParsing, BitVectorTest, 
TestCloudSchemaless, BlockJoinFacetDistribTest, AutoScalingHandlerTest, 
ShufflingReplicaListTransformerTest, SubstringBytesRefFilterTest, 
TestTrackingShardHandlerFactory, TestConfigsApi, TestConfigSetProperties, 
AdminHandlersProxyTest, ConjunctionSolrSpellCheckerTest, 
TestNamedUpdateProcessors, DirectoryFactoryTest, TestCustomSort, 
TestLeaderElectionZkExpiry, TestMultiValuedNumericRangeQuery, SuggesterTest, 
TestCloudConsistency, TestClusterProperties, TestWordDelimiterFilterFactory, 
RAMDirectoryFactoryTest, CursorMarkTest, TestStressUserVersions, 
AnalyticsMergeStrategyTest, NoCacheHeaderTest, DistributedFacetPivotSmallTest, 
UniqFieldsUpdateProcessorFactoryTest, CustomCollectionTest, TestConfig, 
TestBinaryResponseWriter, ManagedSchemaRoundRobinCloudTest, SliceStateTest, 
TestMiniSolrCloudClusterSSL, TestSolrConfigHandlerConcurrent, 
ExternalFileFieldSortTest, TestSearchPerf, TriggerCooldownIntegrationTest, 
TestSizeLimitedDistributedMap, TolerantUpdateProcessorTest, TaggerTest, 
TestNumericTerms32, HdfsRecoverLeaseTest, HDFSCollectionsAPITest, AssignTest, 
BufferStoreTest, CdcrBidirectionalTest, TestNumericTokenStream, 
OutputWriterTest, CachingDirectoryFactoryTest, ChaosMonkeySafeLeaderTest, 
PKIAuthenticationIntegrationTest, IgnoreLargeDocumentProcessorFactoryTest, 
RequestLoggingTest, HdfsAutoAddReplicasIntegrationTest, 
TestClusterStateMutator, TestSQLHandler, 
PeerSyncWithIndexFingerprintCachingTest, DistributedQueueTest, 
IndexBasedSpellCheckerTest, TestAnalyzedSuggestions, HighlighterTest, 
TestFieldResource, LeaderFailoverAfterPartitionTest, SolrTestCaseJ4Test, 
CloudMLTQParserTest, ShardRoutingCustomTest, TestCoreDiscovery, 
HdfsSyncSliceTest, AddReplicaTest, OverseerRolesTest, TestJoin, 
TestExpandComponent, TestGeoJSONResponseWriter, MaxSizeAutoCommitTest, 
RollingRestartTest, TestExecutePlanAction, ReplicaListTransformerTest, 
ForceLeaderTest, TestSolrConfigHandler, CacheHeaderTest, 
TestScoreJoinQPNoScore, TestSolrCloudWithKerberosAlt, TestFreeTextSuggestions, 
SimpleCollectionCreateDeleteTest, TestPHPSerializedResponseWriter, 
TestNestedUpdateProcessor, TestRandomRequestDistribution, 
TestManagedStopFilterFactory, TestBlobHandler, BadComponentTest, 
TestReloadAndDeleteDocs, TestClusterStateProvider, BadCopyFieldTest, 
TestFieldCacheReopen, AsyncCallRequestStatusResponseTest, 
CollectionsAPIAsyncDistributedZkTest, TestMultiWordSynonyms, 
MetricsHistoryHandlerTest, CleanupOldIndexTest, TestDistribIDF, 
ConvertedLegacyTest, TestCloudPseudoReturnFields, JsonLoaderTest, 
TestImpersonationWithHadoopAuth, SolrMetricReporterTest, 
TestInPlaceUpdatesDistrib, DOMUtilTest, TestNRTOpen, TestDocumentBuilder, 
HdfsChaosMonkeyNothingIsSafeTest, TestComplexPhraseLeadingWildcard, 
NumericFieldsTest, DistribCursorPagingTest, TestFunctionQuery, 
ImplicitSnitchTest, LeaderVoteWaitTimeoutTest, CursorPagingTest, 
DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, TestCrossCoreJoin, 
TestCursorMarkWithoutUniqueKey, TestDistributedGrouping, TestRandomFaceting, 
TestSimpleTrackingShardHandler, TestLuceneIndexBackCompat, 
BasicDistributedZkTest, BasicZkTest, CloudExitableDirectoryReaderTest, 
ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, 
CollectionStateFormat2Test, DeleteLastCustomShardedReplicaTest, 
DeleteReplicaTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, 
NodeMutatorTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, 
OverseerTaskQueueTest, OverseerTest, RecoveryZkTest, RemoteQueryErrorTest, 
SolrCLIZkUtilsTest, SolrXmlInZkTest, TestCloudDeleteByQuery, TestConfigSetsAPI, 
TestDistributedMap, TestDownShardTolerantSearch, 
TestExclusionRuleCollectionAccess, TestPullReplica, TestZkChroot, 
TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, 
ZkFailoverTest, TestRequestStatusCollectionAPI, MetricTriggerTest, 
NodeAddedTriggerIntegrationTest, NodeLostTriggerTest]
   [junit4] Completed [522/828 (3!)] on J2 in 23.80s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 10002 lines...]
BUILD FAILED
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:633: The 
following error occurred while executing this line:
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:128: 
Found 1 violations in source files (Unescaped symbol "<-" on line #249).

Total time: 173 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to