You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/03/13 11:03:02 UTC
[JENKINS] Lucene-Solr-8.0-Linux (32bit/jdk1.8.0_172) - Build # 266
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.0-Linux/266/
Java: 32bit/jdk1.8.0_172 -server -XX:+UseConcMarkSweepGC
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudSearcherWarming
Error Message:
5 threads leaked from SUITE scope at org.apache.solr.cloud.TestCloudSearcherWarming: 1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming] at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method) at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196) at java.lang.Thread.run(Thread.java:748) 2) Thread[id=31680, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSearcherWarming] at java.lang.Object.wait(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147) 3) Thread[id=31682, name=ProcessThread(sid:0 cport:36151):, state=WAITING, group=TGRP-TestCloudSearcherWarming] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123) 4) Thread[id=31678, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-TestCloudSearcherWarming] at java.lang.Object.wait(Native Method) at java.lang.Thread.join(Thread.java:1252) at java.lang.Thread.join(Thread.java:1326) at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313) at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:343) at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:564) 5) Thread[id=31681, name=SyncThread:0, state=WAITING, group=TGRP-TestCloudSearcherWarming] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:127)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.TestCloudSearcherWarming:
1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming]
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
at java.lang.Thread.run(Thread.java:748)
2) Thread[id=31680, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSearcherWarming]
at java.lang.Object.wait(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147)
3) Thread[id=31682, name=ProcessThread(sid:0 cport:36151):, state=WAITING, group=TGRP-TestCloudSearcherWarming]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123)
4) Thread[id=31678, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-TestCloudSearcherWarming]
at java.lang.Object.wait(Native Method)
at java.lang.Thread.join(Thread.java:1252)
at java.lang.Thread.join(Thread.java:1326)
at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313)
at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:343)
at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:564)
5) Thread[id=31681, name=SyncThread:0, state=WAITING, group=TGRP-TestCloudSearcherWarming]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:127)
at __randomizedtesting.SeedInfo.seed([6C4406E7EA72A818]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudSearcherWarming
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming] at sun.nio.ch.EPollArrayWrapper.interrupt(Native Method) at sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317) at sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:207) at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213) at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:92) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming]
at sun.nio.ch.EPollArrayWrapper.interrupt(Native Method)
at sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)
at sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:207)
at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)
at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:92)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([6C4406E7EA72A818]:0)
FAILED: org.apache.solr.cloud.TestCloudSearcherWarming.testRepFactor1LeaderStartup
Error Message:
No live SolrServers available to handle this request
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:343)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:213)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1110)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:224)
at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:517)
at org.apache.solr.cloud.TestCloudSearcherWarming.tearDown(TestCloudSearcherWarming.java:78)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:996)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 14345 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCloudSearcherWarming
[junit4] 2> 1713786 INFO (SUITE-TestCloudSearcherWarming-seed#[6C4406E7EA72A818]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/init-core-data-001
[junit4] 2> 1713787 WARN (SUITE-TestCloudSearcherWarming-seed#[6C4406E7EA72A818]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 1713787 INFO (SUITE-TestCloudSearcherWarming-seed#[6C4406E7EA72A818]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1713788 INFO (SUITE-TestCloudSearcherWarming-seed#[6C4406E7EA72A818]-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> 1713790 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRepFactor1LeaderStartup
[junit4] 2> 1713790 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001
[junit4] 2> 1713790 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1713790 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1713791 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1713891 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.ZkTestServer start zk server on port:36151
[junit4] 2> 1713891 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36151
[junit4] 2> 1713891 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36151
[junit4] 2> 1713892 INFO (zkConnectionManagerCallback-10303-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1713894 INFO (zkConnectionManagerCallback-10305-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1713895 INFO (zkConnectionManagerCallback-10307-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1713896 WARN (jetty-launcher-10308-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_172-b11
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1713896 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1713897 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1705a4b{/solr,null,AVAILABLE}
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1daf5dd{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38351}
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.e.j.s.Server Started @1713946ms
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38351}
[junit4] 2> 1713898 ERROR (jetty-launcher-10308-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1713898 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-03-13T10:43:35.808Z
[junit4] 2> 1713899 INFO (zkConnectionManagerCallback-10310-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1713902 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1715905 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1715910 WARN (jetty-launcher-10308-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@bc27e4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1715913 WARN (jetty-launcher-10308-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@e7094b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1715914 INFO (jetty-launcher-10308-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36151/solr
[junit4] 2> 1715925 INFO (zkConnectionManagerCallback-10316-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1715933 INFO (zkConnectionManagerCallback-10318-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1715970 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38351_solr
[junit4] 2> 1715970 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.c.Overseer Overseer (id=72191837531144197-127.0.0.1:38351_solr-n_0000000000) starting
[junit4] 2> 1715972 INFO (zkConnectionManagerCallback-10325-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1715974 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36151/solr ready
[junit4] 2> 1715974 INFO (OverseerStateUpdate-72191837531144197-127.0.0.1:38351_solr-n_0000000000) [n:127.0.0.1:38351_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38351_solr
[junit4] 2> 1715974 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38351_solr
[junit4] 2> 1715975 INFO (OverseerStateUpdate-72191837531144197-127.0.0.1:38351_solr-n_0000000000) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1715985 INFO (zkCallback-10324-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1715996 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1716021 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38351.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14ce08c
[junit4] 2> 1716030 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38351.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14ce08c
[junit4] 2> 1716031 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38351.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14ce08c
[junit4] 2> 1716032 INFO (jetty-launcher-10308-thread-1) [n:127.0.0.1:38351_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/.
[junit4] 2> 1716073 INFO (zkConnectionManagerCallback-10331-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1716074 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1716074 INFO (TEST-TestCloudSearcherWarming.testRepFactor1LeaderStartup-seed#[6C4406E7EA72A818]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36151/solr ready
[junit4] 2> 1716077 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&name=testRepFactor1LeaderStartup&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:38351_solr&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1716079 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testRepFactor1LeaderStartup
[junit4] 2> 1716081 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/protwords.txt
[junit4] 2> 1716081 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
[junit4] 2> 1716082 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testRepFactor1LeaderStartup.AUTOCREATED/solrconfig.xml
[junit4] 2> 1716083 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/synonyms.txt
[junit4] 2> 1716083 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/stopwords.txt
[junit4] 2> 1716085 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1716086 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1716087 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1716087 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1716088 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1716093 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1716094 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1716095 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1716096 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1716097 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1716098 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1716099 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1716100 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1716100 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1716101 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1716102 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1716103 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1716104 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1716104 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1716105 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1716106 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1716106 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1716107 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1716108 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1716108 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1716109 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1716109 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1716110 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1716112 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1716113 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1716113 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1716114 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1716115 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1716116 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1716117 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1716118 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1716119 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1716119 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testRepFactor1LeaderStartup.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1716121 INFO (OverseerThreadFactory-9313-thread-1-processing-n:127.0.0.1:38351_solr) [n:127.0.0.1:38351_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/testRepFactor1LeaderStartup.AUTOCREATED/params.json
[junit4] 2> 1716226 INFO (OverseerStateUpdate-72191837531144197-127.0.0.1:38351_solr-n_0000000000) [n:127.0.0.1:38351_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testRepFactor1LeaderStartup",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testRepFactor1LeaderStartup_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:38351/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1716428 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testRepFactor1LeaderStartup.AUTOCREATED&newCollection=true&name=testRepFactor1LeaderStartup_shard1_replica_n1&action=CREATE&numShards=1&collection=testRepFactor1LeaderStartup&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1716428 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1717438 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1717438 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1717438 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1717439 WARN (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1717447 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1717470 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.IndexSchema [testRepFactor1LeaderStartup_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1717639 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1717667 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'testRepFactor1LeaderStartup_shard1_replica_n1' using configuration from collection testRepFactor1LeaderStartup, trusted=true
[junit4] 2> 1717669 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38351.solr.core.testRepFactor1LeaderStartup.shard1.replica_n1' (registry 'solr.core.testRepFactor1LeaderStartup.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14ce08c
[junit4] 2> 1717669 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [[testRepFactor1LeaderStartup_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/./testRepFactor1LeaderStartup_shard1_replica_n1/data/]
[junit4] 2> 1717737 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1717790 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1717790 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1717792 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1717792 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1717794 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1bd2448[testRepFactor1LeaderStartup_shard1_replica_n1] main]
[junit4] 2> 1717795 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testRepFactor1LeaderStartup.AUTOCREATED
[junit4] 2> 1717795 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testRepFactor1LeaderStartup.AUTOCREATED
[junit4] 2> 1717795 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
[junit4] 2> 1717795 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1717796 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1717796 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1717797 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1717797 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627886787973087232
[junit4] 2> 1717799 INFO (searcherExecutor-9318-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@1bd2448[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1717799 INFO (searcherExecutor-9318-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1717799 INFO (searcherExecutor-9318-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1717800 INFO (searcherExecutor-9318-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1] Registered new searcher Searcher@1bd2448[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1717801 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testRepFactor1LeaderStartup/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1717801 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testRepFactor1LeaderStartup/leaders/shard1
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ has no replicas
[junit4] 2> 1717803 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testRepFactor1LeaderStartup/leaders/shard1/leader after winning as /collections/testRepFactor1LeaderStartup/leader_elect/shard1/election/72191837531144197-core_node2-n_0000000000
[junit4] 2> 1717805 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ shard1
[junit4] 2> 1717905 INFO (zkCallback-10317-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testRepFactor1LeaderStartup/state.json] for collection [testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1717906 INFO (zkCallback-10317-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testRepFactor1LeaderStartup/state.json] for collection [testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1717906 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1717907 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testRepFactor1LeaderStartup.AUTOCREATED&newCollection=true&name=testRepFactor1LeaderStartup_shard1_replica_n1&action=CREATE&numShards=1&collection=testRepFactor1LeaderStartup&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1479
[junit4] 2> 1717909 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1718007 INFO (zkCallback-10317-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testRepFactor1LeaderStartup/state.json] for collection [testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1718007 INFO (zkCallback-10317-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testRepFactor1LeaderStartup/state.json] for collection [testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1718008 INFO (zkCallback-10317-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testRepFactor1LeaderStartup/state.json] for collection [testRepFactor1LeaderStartup] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1718008 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testRepFactor1LeaderStartup&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:38351_solr&wt=javabin&version=2} status=0 QTime=1930
[junit4] 2> java.lang.RuntimeException
[junit4] 2> at org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener.init(TestCloudSearcherWarming.java:305)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:859)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.verifyClass(SolrConfigHandler.java:558)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.updateNamedPlugin(SolrConfigHandler.java:529)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:480)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:345)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:158)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:136)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2559)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 1> {event=newSearcher}
[junit4] 2> java.lang.RuntimeException
[junit4] 2> at org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener.init(TestCloudSearcherWarming.java:305)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:859)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.verifyClass(SolrConfigHandler.java:558)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.updateNamedPlugin(SolrConfigHandler.java:529)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:480)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:345)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:158)
[junit4] 2> at org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:136)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2559)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:394)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:340)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:164)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1610)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:540)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1588)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:255)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1345)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:203)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:480)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1557)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:201)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1247)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:144)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:703)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:502)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:364)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:260)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:305)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:118)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:765)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:683)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 1> {event=firstSearcher}
[junit4] 2> 1718078 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Executed config commands successfully and persisted to ZK [{"add-listener":{
[junit4] 2> "name":"newSearcherListener",
[junit4] 2> "event":"newSearcher",
[junit4] 2> "class":"org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener"}}, {"add-listener":{
[junit4] 2> "name":"firstSearcherListener",
[junit4] 2> "event":"firstSearcher",
[junit4] 2> "class":"org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener"}}]
[junit4] 2> 1718080 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Waiting up to 30 secs for 1 replicas to set the property overlay to be of version 0 for collection testRepFactor1LeaderStartup
[junit4] 2> 1718080 INFO (Thread-7306) [ ] o.a.s.c.SolrCore config update listener called for core testRepFactor1LeaderStartup_shard1_replica_n1
[junit4] 2> 1718080 INFO (Thread-7306) [ ] o.a.s.c.SolrCore core reload testRepFactor1LeaderStartup_shard1_replica_n1
[junit4] 2> 1718080 INFO (OverseerCollectionConfigSetProcessor-72191837531144197-127.0.0.1:38351_solr-n_0000000000) [n:127.0.0.1:38351_solr ] 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> 1718081 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1718146 INFO (Thread-7306) [ ] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 1718146 INFO (Thread-7306) [ ] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 1718146 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/extraction/lib
[junit4] 2> 1718147 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1718147 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/clustering/lib
[junit4] 2> 1718147 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1718147 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/langid/lib
[junit4] 2> 1718148 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1718148 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../contrib/velocity/lib
[junit4] 2> 1718148 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-velocity-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1718148 WARN (Thread-7306) [ ] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist filtered by solr-ltr-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1/../../../../dist
[junit4] 2> 1718158 INFO (Thread-7306) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1718182 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1718183 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.S.Request [testRepFactor1LeaderStartup_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1718183 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1718183 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ for prop overlay after 1 attempts
[junit4] 2> 1718183 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1718184 INFO (Thread-7306) [ ] o.a.s.s.IndexSchema [testRepFactor1LeaderStartup_shard1_replica_n1] Schema name=default-config
[junit4] 2> 1718286 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1718286 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.S.Request [testRepFactor1LeaderStartup_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1718286 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1718286 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ for prop overlay after 2 attempts
[junit4] 2> 1718286 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1718364 INFO (Thread-7306) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1718386 INFO (Thread-7306) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'testRepFactor1LeaderStartup_shard1_replica_n1' using configuration from collection testRepFactor1LeaderStartup
[junit4] 2> 1718387 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler expecting overlay version 0 but my version is -1
[junit4] 2> 1718387 INFO (qtp5506882-31700) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.S.Request [testRepFactor1LeaderStartup_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1718387 INFO (SolrConfigHandler-refreshconf) [ ] o.a.s.h.SolrConfigHandler Another reload is in progress . Not doing anything
[junit4] 2> 1718387 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Could not get expectedVersion 0 from http://127.0.0.1:38351/solr/testRepFactor1LeaderStartup_shard1_replica_n1/ for prop overlay after 3 attempts
[junit4] 2> 1718387 INFO (solrHandlerExecutor-9323-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Time elapsed : 0 secs, maxWait 30
[junit4] 2> 1718409 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38351.solr.core.testRepFactor1LeaderStartup.shard1.replica_n1' (registry 'solr.core.testRepFactor1LeaderStartup.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@14ce08c
[junit4] 2> 1718409 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [[testRepFactor1LeaderStartup_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/testRepFactor1LeaderStartup_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.TestCloudSearcherWarming_6C4406E7EA72A818-001/tempDir-001/node1/./testRepFactor1LeaderStartup_shard1_replica_n1/data/]
[junit4] 2> java.lang.RuntimeException
[junit4] 2> at org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener.init(TestCloudSearcherWarming.java:305)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:859)
[junit4] 2> at org.apache.solr.core.SolrCore.initListeners(SolrCore.java:605)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:959)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:668)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1450)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$21(SolrCore.java:3049)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$21(ZkController.java:2468)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 1> {event=newSearcher}
[junit4] 2> java.lang.RuntimeException
[junit4] 2> at org.apache.solr.cloud.TestCloudSearcherWarming$SleepingSolrEventListener.init(TestCloudSearcherWarming.java:305)
[junit4] 2> at org.apache.solr.core.SolrCore.createInitInstance(SolrCore.java:859)
[junit4] 2> at org.apache.solr.core.SolrCore.initListeners(SolrCore.java:601)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:959)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:668)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1450)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$21(SolrCore.java:3049)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$21(ZkController.java:2468)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 1> {event=firstSearcher}
[junit4] 2> 1718412 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 1718456 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1718456 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1718458 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11443c8[testRepFactor1LeaderStartup_shard1_replica_n1] main]
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testRepFactor1LeaderStartup.AUTOCREATED
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testRepFactor1LeaderStartup.AUTOCREATED
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testRepFactor1LeaderStartup.AUTOCREATED/managed-schema
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1718460 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 1718461 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1718462 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1627886788670390272
[junit4] 2> 1718463 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@11443c8[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1718463 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1718463 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1718466 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1718467 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1] Registered new searcher Searcher@11443c8[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1718467 INFO (Thread-7306) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@184b16[testRepFactor1LeaderStartup_shard1_replica_n1] main]
[junit4] 2> 1718467 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@184b16[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1718467 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1718470 INFO (searcherExecutor-9324-thread-1-processing-n:127.0.0.1:38351_solr x:testRepFactor1LeaderStartup_shard1_replica_n1 c:testRepFactor1LeaderStartup s:shard1 r:core_node2) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1] Registered new searcher Searcher@184b16[testRepFactor1LeaderStartup_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1718488 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler I already have the expected version 0 of config
[junit4] 2> 1718488 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler isStale false , resourceloader org.apache.solr.cloud.ZkSolrResourceLoader
[junit4] 2> 1718488 INFO (qtp5506882-31699) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.S.Request [testRepFactor1LeaderStartup_shard1_replica_n1] webapp=/solr path=/config/znodeVersion params={overlay=0&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1718489 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.h.SolrConfigHandler Took 411.0ms to set the property overlay to be of version 0 for collection testRepFactor1LeaderStartup
[junit4] 2> 1718489 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.S.Request [testRepFactor1LeaderStartup_shard1_replica_n1] webapp=/solr path=/config params={wt=javabin&version=2} status=0 QTime=455
[junit4] 2> 1718489 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.c.SolrCore [testRepFactor1LeaderStartup_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@129a349
[junit4] 2> 1718489 INFO (qtp5506882-31701) [n:127.0.0.1:38351_solr c:testRepFactor1LeaderStartup s:shard1 r:core_node2 x:testRepFactor1LeaderStartup_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testRepFactor1LeaderStartup.shard1.replica_n1, tag=129a349
[junit4] 2> 1718490 INFO (qtp55
[...truncated too long message...]
terStateUpdateTest, ZkStateReaderTest, TestCoreBackup, TestHalfAndHalfDocValues, BaseCdcrDistributedZkTest, TestLeaderElectionZkExpiry, CleanupOldIndexTest, TestLRUStatsCache, SystemLogListenerTest, ShufflingReplicaListTransformerTest, TestPseudoReturnFields, HttpTriggerListenerTest, TestSolrXml, BadComponentTest, TestFreeTextSuggestions, AnalyticsMergeStrategyTest, ComputePlanActionTest, TestCloudRecovery, TestSolrCoreProperties, SplitShardTest, TestUtils, TestAtomicUpdateErrorCases, SolrGraphiteReporterTest, SystemInfoHandlerTest, TestIntervalFaceting, SegmentsInfoRequestHandlerTest, RestoreTriggerStateTest, TestCollectionAPI, BlobRepositoryCloudTest, TestManagedSynonymGraphFilterFactory, TestManagedResourceStorage, DistributedTermsComponentTest, TestBinaryField, TestOverriddenPrefixQueryForCustomFieldType, RootFieldTest, TestUniqueKeyFieldResource, AddSchemaFieldsUpdateProcessorFactoryTest, AdminHandlersProxyTest, RegexBoostProcessorTest, MetricsHistoryHandlerTest, TestLMDirichletSimilarityFactory, TestDeprecatedFilters, UpdateLogTest, SpatialFilterTest, DistributedQueueTest, TestConfigOverlay, DistributedFacetExistsSmallTest, TestSolrQueryParser, TestRawTransformer, TestInfoStreamLogging, OverseerStatusTest, TestRecoveryHdfs, TestManagedSchemaThreadSafety, CSVRequestHandlerTest, CollectionsAPIDistributedZkTest, TestCustomSort, AddReplicaTest, SolrCLIZkUtilsTest, TestGroupingSearch, TestJsonFacetsWithNestedObjects, TestObjectReleaseTracker, SolrIndexConfigTest, TestRTGBase, PKIAuthenticationIntegrationTest, TestCrossCoreJoin, TestLegacyFieldReuse, TestSchemaManager, HdfsChaosMonkeySafeLeaderTest, TestMacros, TestSlowCompositeReaderWrapper, TestLazyCores, TestNestedUpdateProcessor, TestSolrCloudWithSecureImpersonation, SubstringBytesRefFilterTest, TestSchemaSimilarityResource, EnumFieldTest, TestSkipOverseerOperations, SpatialHeatmapFacetsTest, TestFieldCollectionResource, SampleTest, TestMultiValuedNumericRangeQuery, DocValuesMissingTest, QueryEqualityTest, TestSmileRequest, RequestLoggingTest, LeaderElectionIntegrationTest, DeleteInactiveReplicaTest, TestImpersonationWithHadoopAuth, TestDistributedMissingSort, TestDistribDocBasedVersion, TestTolerantUpdateProcessorRandomCloud, TestTrackingShardHandlerFactory, BufferStoreTest, NoCacheHeaderTest, MetricTriggerTest, TestRebalanceLeaders, TestScoreJoinQPScore, WordBreakSolrSpellCheckerTest, TestXmlQParserPlugin, CursorPagingTest, TestJsonFacetRefinement, DistanceFunctionTest, TestCorePropertiesReload, CdcrVersionReplicationTest, PathHierarchyTokenizerFactoryTest, AddBlockUpdateTest, NodeMutatorTest, OrderedExecutorTest, TestFieldCacheVsDocValues, MissingSegmentRecoveryTest, ReturnFieldsTest, RequestHandlersTest, TestPHPSerializedResponseWriter, BasicZkTest, TestZkChroot, TestRandomDVFaceting, TestNumericTerms32, SolrPluginUtilsTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SolrTestCaseJ4Test, TestDocumentBuilder, TestSimpleTrackingShardHandler, TestWordDelimiterFilterFactory, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, TestJettySolrRunner, ConnectionReuseTest, AsyncCallRequestStatusResponseTest, BasicDistributedZk2Test, ChaosMonkeySafeLeaderTest, ClusterStateTest, CollectionsAPISolrJTest, DeleteShardTest, DistribCursorPagingTest, ForceLeaderTest, FullSolrCloudDistribCmdsTest, HealthCheckHandlerTest, HttpPartitionTest, LeaderTragicEventTest, LegacyCloudClusterPropTest, MetricsHistoryIntegrationTest, MigrateRouteKeyTest, RecoveryZkTest, RemoteQueryErrorTest, ReplicationFactorTest, TestCloudDeleteByQuery, TestCloudPseudoReturnFields, TestCloudSearcherWarming]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestCloudSearcherWarming -Dtests.seed=6C4406E7EA72A818 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestCloudSearcherWarming (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 5 threads leaked from SUITE scope at org.apache.solr.cloud.TestCloudSearcherWarming:
[junit4] > 1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming]
[junit4] > at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrapper.java:269)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:93)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 2) Thread[id=31680, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestCloudSearcherWarming]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:147)
[junit4] > 3) Thread[id=31682, name=ProcessThread(sid:0 cport:36151):, state=WAITING, group=TGRP-TestCloudSearcherWarming]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.server.PrepRequestProcessor.run(PrepRequestProcessor.java:123)
[junit4] > 4) Thread[id=31678, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-TestCloudSearcherWarming]
[junit4] > at java.lang.Object.wait(Native Method)
[junit4] > at java.lang.Thread.join(Thread.java:1252)
[junit4] > at java.lang.Thread.join(Thread.java:1326)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.join(NIOServerCnxnFactory.java:313)
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:343)
[junit4] > at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:564)
[junit4] > 5) Thread[id=31681, name=SyncThread:0, state=WAITING, group=TGRP-TestCloudSearcherWarming]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] > at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] > at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:127)
[junit4] > at __randomizedtesting.SeedInfo.seed([6C4406E7EA72A818]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=31679, name=NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0, state=RUNNABLE, group=TGRP-TestCloudSearcherWarming]
[junit4] > at sun.nio.ch.EPollArrayWrapper.interrupt(Native Method)
[junit4] > at sun.nio.ch.EPollArrayWrapper.interrupt(EPollArrayWrapper.java:317)
[junit4] > at sun.nio.ch.EPollSelectorImpl.wakeup(EPollSelectorImpl.java:207)
[junit4] > at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:213)
[junit4] > at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:219)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:92)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:196)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([6C4406E7EA72A818]:0)
[junit4] Completed [511/839 (1!)] on J0 in 103.88s, 2 tests, 3 errors <<< FAILURES!
[...truncated 46317 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.0-Linux/266/consoleText
[repro] Revision: 7630f7906971d8ac9ae0c2cedd9b6e16364a501e
[repro] Ant options: "-Dargs=-server -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=TestCloudSearcherWarming -Dtests.method=testRepFactor1LeaderStartup -Dtests.seed=6C4406E7EA72A818 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TestCloudSearcherWarming -Dtests.seed=6C4406E7EA72A818 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestCloudSearcherWarming
[repro] ant compile-test
[...truncated 3572 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestCloudSearcherWarming" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseConcMarkSweepGC" -Dtests.seed=6C4406E7EA72A818 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=Pacific/Norfolk -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 75 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.TestCloudSearcherWarming
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS-EA] Lucene-Solr-8.0-Linux
(64bit/jdk-12-ea+shipilev-fastdebug) - Build # 268 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.0-Linux/268/
Java: 64bit/jdk-12-ea+shipilev-fastdebug -XX:-UseCompressedOops -XX:+UseG1GC
All tests passed
Build Log:
[...truncated 3355 lines...]
[junit4] JVM J2: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91412020146699278933213.sysout
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] # To suppress the following error report, specify this argument
[junit4] # after -XX: or in .hotspotrc: SuppressErrorAt=/split_if.cpp:322
[junit4] #
[junit4] # A fatal error has been detected by the Java Runtime Environment:
[junit4] #
[junit4] # Internal Error (/home/buildbot/worker/jdk12u-linux/build/src/hotspot/share/opto/split_if.cpp:322), pid=4737, tid=4772
[junit4] # assert(prior_n->is_Region()) failed: must be a post-dominating merge point
[junit4] #
[junit4] # JRE version: OpenJDK Runtime Environment (12.0) (fastdebug build 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229)
[junit4] # Java VM: OpenJDK 64-Bit Server VM (fastdebug 12-testing+0-builds.shipilev.net-openjdk-jdk12-b109-20190215-jdk-1229, mixed mode, tiered, g1 gc, linux-amd64)
[junit4] # Problematic frame:
[junit4] # V [libjvm.so+0x17da240] PhaseIdealLoop::spinup(Node*, Node*, Node*, Node*, Node*, small_cache*) [clone .part.43]+0x330
[junit4] #
[junit4] # No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
[junit4] #
[junit4] # An error report file with more information is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/J2/hs_err_pid4737.log
[junit4] #
[junit4] # Compiler replay data is saved as:
[junit4] # /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/J2/replay_pid4737.log
[junit4] #
[junit4] # If you would like to submit a bug report, please visit:
[junit4] # http://bugreport.java.com/bugreport/crash.jsp
[junit4] #
[junit4] Current thread is 4772
[junit4] Dumping core ...
[junit4] <<< JVM J2: EOF ----
[junit4] JVM J2: stderr was not empty, see: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91415853644180538517273.syserr
[junit4] >>> JVM J2 emitted unexpected output (verbatim) ----
[junit4] OpenJDK 64-Bit Server VM warning: increase O_BUFLEN in ostream.hpp -- output truncated
[junit4] <<< JVM J2: EOF ----
[...truncated 117 lines...]
[junit4] ERROR: JVM J2 ended with an exception, command line: /home/jenkins/tools/java/64bit/jdk-12-ea+shipilev-fastdebug/bin/java -XX:-UseCompressedOops -XX:+UseG1GC -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/heapdumps -ea -esa --illegal-access=deny -Dtests.prefix=tests -Dtests.seed=2993761AFD16BDFC -Xmx512M -Dtests.iters= -Dtests.verbose=false -Dtests.infostream=false -Dtests.codec=random -Dtests.postingsformat=random -Dtests.docvaluesformat=random -Dtests.locale=random -Dtests.timezone=random -Dtests.directory=random -Dtests.linedocsfile=europarl.lines.txt.gz -Dtests.luceneMatchVersion=8.0.0 -Dtests.cleanthreads=perMethod -Djava.util.logging.config.file=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/tools/junit4/logging.properties -Dtests.nightly=false -Dtests.weekly=false -Dtests.monster=false -Dtests.slow=true -Dtests.asserts=true -Dtests.multiplier=3 -DtempDir=./temp -Djava.io.tmpdir=./temp -Dcommon.dir=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene -Dclover.db.dir=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/clover/db -Djava.security.policy=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/tools/junit4/tests.policy -Dtests.LUCENE_VERSION=8.0.0 -Djetty.testMode=1 -Djetty.insecurerandom=1 -Dsolr.directoryFactory=org.apache.solr.core.MockDirectoryFactory -Djava.awt.headless=true -Djdk.map.althashing.threshold=0 -Dtests.src.home=/home/jenkins/workspace/Lucene-Solr-8.0-Linux -Djava.security.egd=file:/dev/./urandom -Djunit4.childvm.cwd=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/J2 -Djunit4.tempDir=/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp -Djunit4.childvm.id=2 -Djunit4.childvm.count=3 -Dfile.encoding=ISO-8859-1 -Djava.security.manager=org.apache.lucene.util.TestSecurityManager -Dtests.filterstacks=true -Dtests.leaveTemporary=false -Dtests.badapples=false -classpath /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/classes/test:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/test-framework/classes/java:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/codecs/classes/java:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/core/classes/java:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/test-framework/lib/hamcrest-core-1.3.jar:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/test-framework/lib/junit-4.12.jar:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.7.2.jar:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/classes/java9:/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/classes/java:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-launcher.jar:/home/jenkins/.ant/lib/ivy-2.4.0.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-netrexx.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-javamail.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-net.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-oro.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jmf.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-log4j.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jsch.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jai.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit4.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-junit.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-jdepend.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-testutil.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-antlr.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-swing.jar:/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2/lib/ant-commons-logging.jar:/home/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.7.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91473170770893569522.events @/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_9145957884996819443180.suites -stdin
[junit4] ERROR: JVM J2 ended with an exception: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91412020146699278933213.sysout See process stderr at: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91415853644180538517273.syserr
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1542)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:123)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:997)
[junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:994)
[junit4] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[junit4] at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[junit4] at java.base/java.lang.Thread.run(Thread.java:835)
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/build.xml:633: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/build.xml:577: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/build.xml:59: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build.xml:479: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/common-build.xml:2268: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/analysis/build.xml:110: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/analysis/build.xml:38: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/module-build.xml:67: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/common-build.xml:1572: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/common-build.xml:1099: At least one slave process threw an exception, first: Forked process returned with error code: 134. Very likely a JVM crash. See process stdout at: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91412020146699278933213.sysout See process stderr at: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/lucene/build/analysis/common/test/temp/junit4-J2-20190313_205426_91415853644180538517273.syserr
Total time: 44 minutes 45 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS] Lucene-Solr-8.0-Linux (32bit/jdk1.8.0_172) - Build # 267
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.0-Linux/267/
Java: 32bit/jdk1.8.0_172 -server -XX:+UseG1GC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.OverseerTest
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([B2ABAA7905DCC989]:0)
FAILED: org.apache.solr.cloud.OverseerTest.testOverseerFailure
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([B2ABAA7905DCC989]:0)
Build Log:
[...truncated 15419 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-8.0-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerTest_B2ABAA7905DCC989-001/init-core-data-001
[junit4] 2> 330758 WARN (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=24 numCloses=24
[junit4] 2> 330759 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 330760 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 330761 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 330761 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 330761 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 330762 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 330862 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41563
[junit4] 2> 330862 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41563
[junit4] 2> 330862 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41563
[junit4] 2> 330865 INFO (zkConnectionManagerCallback-2403-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330867 INFO (zkConnectionManagerCallback-2405-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330867 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 330867 INFO (SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 330871 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBadQueueItem
[junit4] 2> 330901 INFO (zkConnectionManagerCallback-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330908 WARN (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 330908 WARN (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3538fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 331088 INFO (zkConnectionManagerCallback-2414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331187 INFO (zkConnectionManagerCallback-2419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331188 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 331190 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 331190 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240194-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 331194 INFO (OverseerStateUpdate-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 331201 INFO (zkConnectionManagerCallback-2423-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331201 DEBUG (OverseerAutoScalingTriggerThread-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: []
[junit4] 2> 331205 DEBUG (ScheduledTrigger-1916-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 0 and last live nodes: 0
[junit4] 2> 331213 INFO (zkCallback-2418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 331213 INFO (zkCallback-2406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 331213 INFO (zkCallback-2404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 331213 INFO (zkCallback-2422-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 331320 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 331321 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72192857951240197-127.0.0.1_core1-n_0000000000
[junit4] 2> 331439 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 331440 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72192857951240197-127.0.0.1_core2-n_0000000000
[junit4] 2> 331543 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3
[junit4] 2> 331544 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/72192857951240197-127.0.0.1_core3-n_0000000000
[junit4] 2> 331650 ERROR (OverseerStateUpdate-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.o.CollectionMutator Skipping invalid Overseer message because it has no collection specified: {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":"",
[junit4] 2> "shard":"shard1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://127.0.0.1/solr/"}
[junit4] 2> 331650 ERROR (OverseerStateUpdate-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.o.CollectionMutator Skipping invalid Overseer message because it has no collection specified: {
[junit4] 2> "operation":"deletecore",
[junit4] 2> "node_name":"127.0.0.1",
[junit4] 2> "core":"core0",
[junit4] 2> "core_node_name":"node0",
[junit4] 2> "collection":""}
[junit4] 2> 331754 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard1
[junit4] 2> 331756 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard1/leader after winning as /collections/collection2/leader_elect/shard1/election/72192857951240197-127.0.0.1_core1-n_0000000000
[junit4] 2> 331860 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard2
[junit4] 2> 331861 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard2/leader after winning as /collections/collection2/leader_elect/shard2/election/72192857951240197-127.0.0.1_core2-n_0000000000
[junit4] 2> 331965 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard3
[junit4] 2> 331966 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard3/leader after winning as /collections/collection2/leader_elect/shard3/election/72192857951240197-127.0.0.1_core3-n_0000000000
[junit4] 2> 332078 INFO (zkCallback-2418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 332078 INFO (zkCallback-2422-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 332080 INFO (zkCallback-2406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 332081 INFO (zkCallback-2404-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 332113 INFO (closeThreadPool-2427-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72192857951240194-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 332113 INFO (OverseerStateUpdate-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 332113 INFO (OverseerAutoScalingTriggerThread-72192857951240194-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 332123 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332142 INFO (TEST-OverseerTest.testBadQueueItem-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBadQueueItem
[junit4] 2> 332147 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExceptionWhenFlushClusterState
[junit4] 2> 332206 INFO (zkConnectionManagerCallback-2431-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332218 WARN (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 332232 WARN (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@ed776d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 332257 INFO (zkConnectionManagerCallback-2438-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332273 INFO (zkConnectionManagerCallback-2443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332274 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 332275 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 332276 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240198-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 332278 INFO (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 332305 WARN (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception when process message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "createNodeSet":""}, consider as bad message and poll out from the queue
[junit4] 2> 332305 ERROR (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer when process message from work queue, retrying
[junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /collections/collection1/state.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:792) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:204) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.enqueueUpdate(ZkStateWriter.java:130) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.processQueueItem(Overseer.java:303) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:177) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332323 DEBUG (OverseerAutoScalingTriggerThread-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: []
[junit4] 2> 332326 DEBUG (ScheduledTrigger-1921-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 0 and last live nodes: 0
[junit4] 2> 332425 ERROR (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer main queue loop
[junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /collections/collection1/state.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:792) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:204) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:247) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332426 WARN (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception when process message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "numShards":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "createNodeSet":""}, consider as bad message and poll out from the queue
[junit4] 2> 332426 ERROR (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer when process message from work queue, retrying
[junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /collections/collection1/state.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:792) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:415) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:204) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.enqueueUpdate(ZkStateWriter.java:130) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.processQueueItem(Overseer.java:303) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:177) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332432 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 0x1007b0595810008, likely client has closed socket
[junit4] 2> 332432 INFO (closeThreadPool-2445-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72192857951240198-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 332432 INFO (OverseerStateUpdate-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 332432 INFO (OverseerAutoScalingTriggerThread-72192857951240198-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 332433 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332448 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExceptionWhenFlushClusterState
[junit4] 2> 332452 INFO (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExternalClusterStateChangeBehavior
[junit4] 2> 332483 INFO (zkConnectionManagerCallback-2449-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332485 WARN (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 332489 WARN (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1606e98[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 332496 INFO (zkConnectionManagerCallback-2456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332502 INFO (zkConnectionManagerCallback-2461-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332503 INFO (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 332504 INFO (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 332504 INFO (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240201-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 332509 INFO (OverseerStateUpdate-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 332511 DEBUG (OverseerAutoScalingTriggerThread-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: []
[junit4] 2> 332512 DEBUG (ScheduledTrigger-1926-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 0 and last live nodes: 0
[junit4] 2> 332618 INFO (OverseerStateUpdate-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"test",
[junit4] 2> "shard":"x",
[junit4] 2> "base_url":"http://127.0.0.1/solr",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "state":"down"}
[junit4] 2> 332718 INFO (zkCallback-2404-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeCreated path:/collections/test/state.json] for collection [test] has occurred - updating... (live nodes size: [0])
[junit4] 2> 332719 ERROR (OverseerStateUpdate-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer main queue loop
[junit4] 2> org.apache.zookeeper.KeeperException$BadVersionException: KeeperErrorCode = BadVersion for /clusterstate.json
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:118) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1336) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$setData$6(SolrZkClient.java:370) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:370) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:220) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:247) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332740 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 0x1007b059581000b, likely client has closed socket
[junit4] 2> 332740 INFO (closeThreadPool-2463-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72192857951240201-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 332741 INFO (OverseerStateUpdate-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 332741 INFO (OverseerAutoScalingTriggerThread-72192857951240201-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 332746 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 332769 INFO (TEST-OverseerTest.testExternalClusterStateChangeBehavior-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExternalClusterStateChangeBehavior
[junit4] 2> 332775 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDownNodeFailover
[junit4] 2> 332857 INFO (zkConnectionManagerCallback-2465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332882 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 332909 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1ae7128[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 332936 INFO (zkConnectionManagerCallback-2472-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332945 INFO (zkConnectionManagerCallback-2477-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 332946 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 332947 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 332948 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240204-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 332957 INFO (OverseerStateUpdate-72192857951240204-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 333017 INFO (zkConnectionManagerCallback-2481-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 333021 INFO (zkCallback-2404-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333029 DEBUG (OverseerAutoScalingTriggerThread-72192857951240204-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: []
[junit4] 2> 333031 INFO (zkCallback-2464-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333041 INFO (zkCallback-2476-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333041 DEBUG (ScheduledTrigger-1931-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 0
[junit4] 2> 333045 INFO (zkCallback-2480-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333045 INFO (zkConnectionManagerCallback-2488-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 333046 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333046 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 333150 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection0/leaders/shard1
[junit4] 2> 333151 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection0/leaders/shard1/leader after winning as /collections/collection0/leader_elect/shard1/election/72192857951240207-127.0.0.1_core1-n_0000000000
[junit4] 2> 333260 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 333261 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72192857951240207-127.0.0.1_core1-n_0000000000
[junit4] 2> 333375 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard1
[junit4] 2> 333376 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard1/leader after winning as /collections/collection2/leader_elect/shard1/election/72192857951240207-127.0.0.1_core1-n_0000000000
[junit4] 2> 333525 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection3/leaders/shard1
[junit4] 2> 333529 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection3/leaders/shard1/leader after winning as /collections/collection3/leader_elect/shard1/election/72192857951240207-127.0.0.1_core1-n_0000000000
[junit4] 2> 333635 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection4/leaders/shard1
[junit4] 2> 333636 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection4/leaders/shard1/leader after winning as /collections/collection4/leader_elect/shard1/election/72192857951240207-127.0.0.1_core1-n_0000000000
[junit4] 2> 333745 INFO (zkConnectionManagerCallback-2490-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 333745 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240204-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 333745 INFO (OverseerStateUpdate-72192857951240204-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer According to ZK I (id=72192857951240204-127.0.0.1:41563_solr-n_0000000000) am no longer a leader. propsId=null
[junit4] 2> 333745 INFO (OverseerStateUpdate-72192857951240204-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 333758 INFO (OverseerAutoScalingTriggerThread-72192857951240204-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 333762 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 333763 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 333764 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@173cee3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 333771 INFO (zkConnectionManagerCallback-2497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 333784 INFO (zkConnectionManagerCallback-2502-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 333786 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333786 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 333787 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 333787 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240209-127.0.0.1:41563_solr-n_0000000001) starting
[junit4] 2> 333789 INFO (OverseerStateUpdate-72192857951240209-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 333790 INFO (OverseerStateUpdate-72192857951240209-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 333791 DEBUG (OverseerAutoScalingTriggerThread-72192857951240209-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1]
[junit4] 2> 333793 DEBUG (ScheduledTrigger-1936-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 333797 INFO (zkCallback-2476-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333797 INFO (zkCallback-2487-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333798 INFO (zkCallback-2404-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333801 INFO (zkCallback-2480-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333801 INFO (zkCallback-2489-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333801 INFO (zkCallback-2501-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 333809 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 0x1007b059581000d, likely client has closed socket
[junit4] 2> 333813 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 0x1007b059581000e, likely client has closed socket
[junit4] 2> 333821 INFO (closeThreadPool-2504-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72192857951240204-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 333825 INFO (closeThreadPool-2504-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72192857951240209-127.0.0.1:41563_solr-n_0000000001) closing
[junit4] 2> 333825 INFO (OverseerStateUpdate-72192857951240209-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 333825 INFO (OverseerAutoScalingTriggerThread-72192857951240209-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 333829 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 333846 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDownNodeFailover
[junit4] 2> 333853 INFO (TEST-OverseerTest.testLatchWatcher-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLatchWatcher
[junit4] 2> 333956 INFO (TEST-OverseerTest.testLatchWatcher-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLatchWatcher
[junit4] 2> 333962 INFO (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReplay
[junit4] 2> 334017 INFO (zkConnectionManagerCallback-2510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334020 WARN (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 334025 WARN (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@122e30d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 334029 INFO (zkConnectionManagerCallback-2517-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334037 INFO (zkConnectionManagerCallback-2522-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334038 INFO (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 334039 INFO (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 334040 INFO (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240212-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 334053 INFO (OverseerStateUpdate-72192857951240212-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 334063 DEBUG (OverseerAutoScalingTriggerThread-72192857951240212-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: []
[junit4] 2> 334063 DEBUG (ScheduledTrigger-1941-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 0 and last live nodes: 0
[junit4] 2> 334185 INFO (closeThreadPool-2524-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72192857951240212-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 334185 INFO (OverseerStateUpdate-72192857951240212-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 334185 INFO (OverseerAutoScalingTriggerThread-72192857951240212-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 334218 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 334231 INFO (TEST-OverseerTest.testReplay-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReplay
[junit4] 2> 334236 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerFailure
[junit4] 2> 334285 INFO (zkConnectionManagerCallback-2528-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334291 INFO (zkCallback-2404-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 334298 INFO (zkCallback-2527-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 334352 INFO (zkConnectionManagerCallback-2532-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334381 WARN (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 334405 WARN (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@962242[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 334426 INFO (zkConnectionManagerCallback-2539-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334457 INFO (zkConnectionManagerCallback-2544-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334458 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 334459 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 334461 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 334462 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240216-127.0.0.1:41563_solr-n_0000000000) starting
[junit4] 2> 334469 INFO (OverseerStateUpdate-72192857951240216-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 334471 INFO (OverseerStateUpdate-72192857951240216-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 334521 INFO (zkConnectionManagerCallback-2549-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 334525 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 334525 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 334529 DEBUG (OverseerAutoScalingTriggerThread-72192857951240216-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [node1]
[junit4] 2> 334553 DEBUG (ScheduledTrigger-1946-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 334675 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 334676 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72192857951240215-node1_core1-n_0000000000
[junit4] 2> 334688 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 334689 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72192857951240215-node1_core1-n_0000000001
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=29059102
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=18118362
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=15643607
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=28046242
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=26515281
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=22449375
[junit4] 2> 334761 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=16639209
[junit4] 2> 335032 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 335035 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72192857951240215-node1_core1-n_0000000002
[junit4] 2> 335053 INFO (zkConnectionManagerCallback-2551-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 335053 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240216-127.0.0.1:41563_solr-n_0000000000) closing
[junit4] 2> 335053 INFO (OverseerAutoScalingTriggerThread-72192857951240216-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 335053 INFO (OverseerStateUpdate-72192857951240216-127.0.0.1:41563_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:41563_solr
[junit4] 2> 335058 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?]
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) ~[java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 335060 WARN (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 335061 WARN (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@142ab06[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 335068 INFO (zkConnectionManagerCallback-2558-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 335097 INFO (zkConnectionManagerCallback-2563-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 335098 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 335099 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41563/solr ready
[junit4] 2> 335100 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41563_solr
[junit4] 2> 335101 INFO (TEST-OverseerTest.testOverseerFailure-seed#[B2ABAA7905DCC989]) [ ] o.a.s.c.Overseer Overseer (id=72192857951240220-127.0.0.1:41563_solr-n_0000000001) starting
[junit4] 2> 335105 INFO (OverseerStateUpdate-72192857951240220-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:41563_solr
[junit4] 2> 335107 INFO (OverseerStateUpdate-72192857951240220-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 335149 DEBUG (OverseerAutoScalingTriggerThread-72192857951240220-127.0.0.1:41563_solr-n_0000000001) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [node1]
[junit4] 2> 335237 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 336241 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 337241 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 338241 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 339241 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 340242 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 341242 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 342242 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 343242 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 344242 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 345242 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 346242 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 347243 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 348243 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 349243 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 350243 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 351243 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 352243 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 353243 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 354244 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 355244 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 356244 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 357244 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 358244 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 359244 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 360245 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 361245 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 362245 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 363245 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 364245 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 365245 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 366245 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 367246 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 368246 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 369246 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 370246 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 371246 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 372246 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 373247 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 374247 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 375247 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 376247 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 377247 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 378247 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 379247 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 380248 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 381248 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 382248 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 383248 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 384248 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 385248 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 386249 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 387249 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 388249 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 389249 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 390249 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 391249 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 392249 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 393250 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 394254 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 395254 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 396255 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 397255 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 398255 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 399255 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 400255 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 401256 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 402256 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 403256 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 404256 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 405256 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 406256 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 407256 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 408257 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 409257 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 410257 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 411257 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 412257 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 413257 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 414258 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 415258 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 416258 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 417258 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 418258 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 419258 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 420258 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 421259 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 422259 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 423259 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 424259 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 425259 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 426259 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 427259 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 428260 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 429260 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 430260 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 431260 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 432260 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 433260 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 434261 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 435261 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 436261 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 437261 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 438261 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 439261 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 440262 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 441262 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 442262 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 443262 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 444262 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 445262 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 446263 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 447263 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 448263 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 449263 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 450263 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 451263 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 452263 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 453264 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 454264 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 455264 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 456264 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 457264 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 458264 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 459265 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 460265 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 461265 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 462265 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 463265 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 464265 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 465265 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 466266 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 467268 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 468269 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 469269 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 470269 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 471269 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 472269 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 473269 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 474270 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 475270 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 476270 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 477270 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 478270 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 479270 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 480271 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 481271 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 482271 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 483271 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 484271 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 485271 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 486272 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 487272 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 488272 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 489272 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 490272 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 491273 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 492273 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 493273 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 494273 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 495306 DEBUG (ScheduledTrigger-1951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 496308 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 497309 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 498309 DEBUG (ScheduledTrigger-1951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 499309 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 500309 DEBUG (ScheduledTrigger-1951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 501309 DEBUG (ScheduledTrigger-1951-thread-3) [ ] o.a.s.c.
[...truncated too long message...]
at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1049)
[junit4] 2> 2) Thread[id=7403, name=SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker, state=WAITING, group=TGRP-OverseerTest]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Object.wait(Object.java:502)
[junit4] 2> at org.apache.zookeeper.ClientCnxn.submitRequest(ClientCnxn.java:1411)
[junit4] 2> at org.apache.zookeeper.ClientCnxn.close(ClientCnxn.java:1382)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.close(ZooKeeper.java:686)
[junit4] 2> at org.apache.solr.common.cloud.SolrZooKeeper.close(SolrZooKeeper.java:96)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.closeKeeper(SolrZkClient.java:720)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.close(SolrZkClient.java:691)
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.tryClose(ObjectReleaseTracker.java:85)
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.teardownTestCases(SolrTestCaseJ4.java:324)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor59.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 3) Thread[id=7415, name=zkConnectionManagerCallback-2405-thread-1, state=WAITING, group=TGRP-OverseerTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 4) Thread[id=7410, name=SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker-SendThread(localhost.localdomain:41563), state=TIMED_WAITING, group=TGRP-OverseerTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:152)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1062)
[junit4] 2> 5) Thread[id=7622, name=zkConnectionManagerCallback-2528-thread-1, state=WAITING, group=TGRP-OverseerTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
[junit4] 2> at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 6) Thread[id=7413, name=SUITE-OverseerTest-seed#[B2ABAA7905DCC989]-worker-SendThread(localhost.localdomain:41563), state=TIMED_WAITING, group=TGRP-OverseerTest]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at org.apache.zookeeper.client.StaticHostProvider.next(StaticHostProvider.java:152)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1062)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=976, maxMBSortInHeap=5.229564872540647, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@e17194), locale=ja-JP, timezone=US/Central
[junit4] 2> NOTE: Linux 4.18.0-15-generic i386/Oracle Corporation 1.8.0_172 (32-bit)/cpus=8,threads=7,free=84518304,total=523239424
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest, DistributedFacetPivotWhiteBoxTest, TestFoldingMultitermQuery, TestLegacyFieldCache, TestUnifiedSolrHighlighter, ReplicaListTransformerTest, TestDelegationWithHadoopAuth, TriggerSetPropertiesIntegrationTest, PeerSyncWithLeaderTest, CreateRoutedAliasTest, InfixSuggestersTest, URLClassifyProcessorTest, TestPKIAuthenticationPlugin, LeaderVoteWaitTimeoutTest, TestLRUCache, DistribJoinFromCollectionTest, OverseerRolesTest, TestConfigReload, MoveReplicaHDFSTest, DateMathParserTest, TestPerFieldSimilarityWithDefaultOverride, TestJavabinTupleStreamParser, TestSerializedLuceneMatchVersion, TestSubQueryTransformer, TestFieldCache, CloudMLTQParserTest, BadIndexSchemaTest, DeleteStatusTest, DateRangeFieldTest, TestQueryUtils, HdfsRecoverLeaseTest, HdfsChaosMonkeyNothingIsSafeTest, TestReloadAndDeleteDocs, TestAnalyzeInfixSuggestions, TestCollapseQParserPlugin, DocValuesNotIndexedTest, SolrMetricManagerTest, TestSimExtremeIndexing, DistributedExpandComponentTest, BlockJoinFacetDistribTest, HLLUtilTest, OverseerTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.seed=B2ABAA7905DCC989 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja-JP -Dtests.timezone=US/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | OverseerTest (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([B2ABAA7905DCC989]:0)
[junit4] Completed [742/839 (1!)] on J2 in 7213.09s, 8 tests, 2 errors, 1 skipped <<< FAILURES!
[...truncated 45465 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.0-Linux/267/consoleText
[repro] Revision: 7630f7906971d8ac9ae0c2cedd9b6e16364a501e
[repro] Ant options: "-Dargs=-server -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=OverseerTest -Dtests.method=testOverseerFailure -Dtests.seed=B2ABAA7905DCC989 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja-JP -Dtests.timezone=US/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=OverseerTest -Dtests.seed=B2ABAA7905DCC989 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja-JP -Dtests.timezone=US/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] OverseerTest
[repro] ant compile-test
[...truncated 3572 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.OverseerTest" -Dtests.showOutput=onerror "-Dargs=-server -XX:+UseG1GC" -Dtests.seed=B2ABAA7905DCC989 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ja-JP -Dtests.timezone=US/Central -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 130 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.OverseerTest
[repro] Exiting with code 0
[...truncated 43 lines...]