You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/05/31 07:28:50 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 2553 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/2553/
3 tests failed.
FAILED: org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown
Error Message:
No live SolrServers available to handle this request:[https://127.0.0.1:42575/solr/coll1]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:42575/solr/coll1]
at __randomizedtesting.SeedInfo.seed([815003D8E44CA618:EFC780949DAB009B]:0)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:346)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.cloud.ZkFailoverTest.testRestartZkWhenClusterDown(ZkFailoverTest.java:64)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42575/solr/coll1: no servers hosting shard: shard1
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
... 48 more
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ZkFailoverTest
Error Message:
16 threads leaked from SUITE scope at org.apache.solr.cloud.ZkFailoverTest: 1) Thread[id=30574, name=NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:45121, state=RUNNABLE, group=TGRP-ZkFailoverTest] 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 sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.select(NIOServerCnxnFactory.java:219) at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.run(NIOServerCnxnFactory.java:195) 2) Thread[id=30577, name=ProcessThread(sid:0 cport:45121):, state=WAITING, group=TGRP-ZkFailoverTest] 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:140) 3) Thread[id=30591, name=NIOWorkerThread-7, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=30583, name=NIOWorkerThread-3, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 5) Thread[id=30573, name=NIOServerCxnFactory.SelectorThread-0, state=RUNNABLE, group=TGRP-ZkFailoverTest] 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 sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.select(NIOServerCnxnFactory.java:417) at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.run(NIOServerCnxnFactory.java:382) 6) Thread[id=30579, name=NIOWorkerThread-1, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 7) Thread[id=30575, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157) 8) Thread[id=30572, name=ConnnectionExpirer, state=TIMED_WAITING, group=TGRP-ZkFailoverTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.server.NIOServerCnxnFactory$ConnectionExpirerThread.run(NIOServerCnxnFactory.java:568) 9) Thread[id=30587, name=NIOWorkerThread-4, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 10) Thread[id=30590, name=NIOWorkerThread-6, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 11) Thread[id=30578, name=RequestThrottler, state=WAITING, group=TGRP-ZkFailoverTest] 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.RequestThrottler.run(RequestThrottler.java:139) 12) Thread[id=30576, name=SyncThread:0, state=WAITING, group=TGRP-ZkFailoverTest] 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:170) 13) Thread[id=30582, name=NIOWorkerThread-2, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 14) Thread[id=30588, name=NIOWorkerThread-5, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 15) Thread[id=30592, name=NIOWorkerThread-8, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 16) Thread[id=30571, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-ZkFailoverTest] 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:929) at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:349) at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:580)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 16 threads leaked from SUITE scope at org.apache.solr.cloud.ZkFailoverTest:
1) Thread[id=30574, name=NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:45121, state=RUNNABLE, group=TGRP-ZkFailoverTest]
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 sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.select(NIOServerCnxnFactory.java:219)
at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.run(NIOServerCnxnFactory.java:195)
2) Thread[id=30577, name=ProcessThread(sid:0 cport:45121):, state=WAITING, group=TGRP-ZkFailoverTest]
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:140)
3) Thread[id=30591, name=NIOWorkerThread-7, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=30583, name=NIOWorkerThread-3, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
5) Thread[id=30573, name=NIOServerCxnFactory.SelectorThread-0, state=RUNNABLE, group=TGRP-ZkFailoverTest]
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 sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.select(NIOServerCnxnFactory.java:417)
at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.run(NIOServerCnxnFactory.java:382)
6) Thread[id=30579, name=NIOWorkerThread-1, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
7) Thread[id=30575, name=SessionTracker, state=TIMED_WAITING, group=TGRP-ZkFailoverTest]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157)
8) Thread[id=30572, name=ConnnectionExpirer, state=TIMED_WAITING, group=TGRP-ZkFailoverTest]
at java.lang.Thread.sleep(Native Method)
at org.apache.zookeeper.server.NIOServerCnxnFactory$ConnectionExpirerThread.run(NIOServerCnxnFactory.java:568)
9) Thread[id=30587, name=NIOWorkerThread-4, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
10) Thread[id=30590, name=NIOWorkerThread-6, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
11) Thread[id=30578, name=RequestThrottler, state=WAITING, group=TGRP-ZkFailoverTest]
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.RequestThrottler.run(RequestThrottler.java:139)
12) Thread[id=30576, name=SyncThread:0, state=WAITING, group=TGRP-ZkFailoverTest]
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:170)
13) Thread[id=30582, name=NIOWorkerThread-2, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
14) Thread[id=30588, name=NIOWorkerThread-5, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
15) Thread[id=30592, name=NIOWorkerThread-8, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
16) Thread[id=30571, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-ZkFailoverTest]
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:929)
at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:349)
at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:580)
at __randomizedtesting.SeedInfo.seed([815003D8E44CA618]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ZkFailoverTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=30574, name=NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:45121, state=RUNNABLE, group=TGRP-ZkFailoverTest] at sun.nio.ch.IOUtil.drain(Native Method) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.select(NIOServerCnxnFactory.java:219) at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.run(NIOServerCnxnFactory.java:195) 2) Thread[id=30590, name=NIOWorkerThread-6, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 3) Thread[id=30591, name=NIOWorkerThread-7, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 4) Thread[id=30583, name=NIOWorkerThread-3, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 5) Thread[id=30573, name=NIOServerCxnFactory.SelectorThread-0, state=RUNNABLE, group=TGRP-ZkFailoverTest] at sun.nio.ch.IOUtil.drain(Native Method) at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101) at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.select(NIOServerCnxnFactory.java:417) at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.run(NIOServerCnxnFactory.java:382) 6) Thread[id=30579, name=NIOWorkerThread-1, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 7) Thread[id=30582, name=NIOWorkerThread-2, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=30588, name=NIOWorkerThread-5, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 9) Thread[id=30592, name=NIOWorkerThread-8, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) 10) Thread[id=30587, name=NIOWorkerThread-4, state=WAITING, group=TGRP-ZkFailoverTest] 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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 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=30574, name=NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:45121, state=RUNNABLE, group=TGRP-ZkFailoverTest]
at sun.nio.ch.IOUtil.drain(Native Method)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.select(NIOServerCnxnFactory.java:219)
at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.run(NIOServerCnxnFactory.java:195)
2) Thread[id=30590, name=NIOWorkerThread-6, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
3) Thread[id=30591, name=NIOWorkerThread-7, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
4) Thread[id=30583, name=NIOWorkerThread-3, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
5) Thread[id=30573, name=NIOServerCxnFactory.SelectorThread-0, state=RUNNABLE, group=TGRP-ZkFailoverTest]
at sun.nio.ch.IOUtil.drain(Native Method)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.select(NIOServerCnxnFactory.java:417)
at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.run(NIOServerCnxnFactory.java:382)
6) Thread[id=30579, name=NIOWorkerThread-1, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
7) Thread[id=30582, name=NIOWorkerThread-2, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
8) Thread[id=30588, name=NIOWorkerThread-5, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
9) Thread[id=30592, name=NIOWorkerThread-8, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
10) Thread[id=30587, name=NIOWorkerThread-4, state=WAITING, group=TGRP-ZkFailoverTest]
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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([815003D8E44CA618]:0)
Build Log:
[...truncated 15212 lines...]
[junit4] Suite: org.apache.solr.cloud.ZkFailoverTest
[junit4] 2> 1596392 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1596392 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/data-dir-197-001
[junit4] 2> 1596393 WARN (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 1596393 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1596394 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1596394 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1596395 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001
[junit4] 2> 1596395 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1596395 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1596395 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1596398 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1596495 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 45121
[junit4] 2> 1596495 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45121
[junit4] 2> 1596495 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45121
[junit4] 2> 1596495 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45121
[junit4] 2> 1596497 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1596502 INFO (zkConnectionManagerCallback-17839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1596502 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1596512 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1596513 INFO (zkConnectionManagerCallback-17841-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1596513 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1596514 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1596515 INFO (zkConnectionManagerCallback-17843-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1596516 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1596619 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1596619 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1596620 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1596634 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1596634 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1596635 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1596656 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1596656 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1596656 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1596660 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4e428fe{/solr,null,AVAILABLE}
[junit4] 2> 1596660 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7c583fa1{/solr,null,AVAILABLE}
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37b70903{SSL, (ssl, http/1.1)}{127.0.0.1:33625}
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-1) [ ] o.e.j.s.Server Started @1596690ms
[junit4] 2> 1596661 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:45121/solr, hostPort=33625}
[junit4] 2> 1596662 ERROR (jetty-launcher-17844-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1596662 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1596662 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 1596662 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1596662 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1596662 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-31T07:06:08.250Z
[junit4] 2> 1596663 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45eb7ebb{SSL, (ssl, http/1.1)}{127.0.0.1:42575}
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.e.j.s.Server Started @1596692ms
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:45121/solr, hostPort=42575}
[junit4] 2> 1596664 ERROR (jetty-launcher-17844-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 1596664 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-05-31T07:06:08.252Z
[junit4] 2> 1596665 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1596665 INFO (zkConnectionManagerCallback-17846-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1596665 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1596666 INFO (zkConnectionManagerCallback-17848-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1596666 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1596666 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1596666 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1596669 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1596672 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 1596672 WARN (jetty-launcher-17844-thread-2-SendThread(127.0.0.1:45121)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a48bd4cc0004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a48bd4cc0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a48bd4cc0004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1596673 WARN (jetty-launcher-17844-thread-1-SendThread(127.0.0.1:45121)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a48bd4cc0003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a48bd4cc0003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a48bd4cc0003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1597074 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1597075 WARN (jetty-launcher-17844-thread-2) [ ] 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> 1597076 WARN (jetty-launcher-17844-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@777bcbaf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597076 WARN (jetty-launcher-17844-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@777bcbaf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597080 WARN (jetty-launcher-17844-thread-2) [ ] 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> 1597084 WARN (jetty-launcher-17844-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@42a0bad6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597084 WARN (jetty-launcher-17844-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@42a0bad6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597085 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45121/solr
[junit4] 2> 1597086 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1597087 INFO (zkConnectionManagerCallback-17862-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1597087 INFO (jetty-launcher-17844-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1597190 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1597191 INFO (zkConnectionManagerCallback-17864-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1597191 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1597272 WARN (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1597276 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42575_solr
[junit4] 2> 1597277 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.Overseer Overseer (id=72238514515476486-127.0.0.1:42575_solr-n_0000000000) starting
[junit4] 2> 1597282 INFO (OverseerStateUpdate-72238514515476486-127.0.0.1:42575_solr-n_0000000000) [n:127.0.0.1:42575_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42575_solr
[junit4] 2> 1597282 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42575_solr
[junit4] 2> 1597283 INFO (OverseerStateUpdate-72238514515476486-127.0.0.1:42575_solr-n_0000000000) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1597285 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1597285 WARN (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1597300 INFO (jetty-launcher-17844-thread-2) [n:127.0.0.1:42575_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node2
[junit4] 2> 1597421 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1597422 WARN (jetty-launcher-17844-thread-1) [ ] 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> 1597423 WARN (jetty-launcher-17844-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c684599[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597423 WARN (jetty-launcher-17844-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c684599[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597425 WARN (jetty-launcher-17844-thread-1) [ ] 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> 1597426 WARN (jetty-launcher-17844-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@36e023f8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597426 WARN (jetty-launcher-17844-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@36e023f8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1597427 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45121/solr
[junit4] 2> 1597428 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1597428 INFO (zkConnectionManagerCallback-17882-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1597428 INFO (jetty-launcher-17844-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1597530 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1597531 INFO (zkConnectionManagerCallback-17884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1597531 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1597535 WARN (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1597536 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1597539 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33625_solr as DOWN
[junit4] 2> 1597540 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1597540 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33625_solr
[junit4] 2> 1597541 INFO (zkCallback-17863-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1597541 INFO (zkCallback-17883-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1597542 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1597543 WARN (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1597552 INFO (jetty-launcher-17844-thread-1) [n:127.0.0.1:33625_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node1
[junit4] 2> 1597558 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1597559 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1597559 INFO (zkConnectionManagerCallback-17895-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1597559 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1597560 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1597561 INFO (SUITE-ZkFailoverTest-seed#[815003D8E44CA618]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45121/solr ready
[junit4] 2> 1597582 INFO (TEST-ZkFailoverTest.testRestartZkWhenClusterDown-seed#[815003D8E44CA618]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRestartZkWhenClusterDown
[junit4] 2> 1597610 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection coll1
[junit4] 2> 1597611 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/coll1.AUTOCREATED
[junit4] 2> 1597612 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/coll1.AUTOCREATED/protwords.txt
[junit4] 2> 1597613 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 1597615 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/coll1.AUTOCREATED/solrconfig.xml
[junit4] 2> 1597617 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/coll1.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1597618 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/coll1.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1597619 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/coll1.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1597621 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/coll1.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1597622 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1597623 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/coll1.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1597625 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/coll1.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1597626 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1597628 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/coll1.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1597629 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1597630 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1597632 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/coll1.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1597633 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1597634 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/coll1.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1597636 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1597637 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1597638 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1597639 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/coll1.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1597641 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/coll1.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1597642 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/coll1.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1597644 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/coll1.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1597645 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/coll1.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1597647 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/coll1.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1597648 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/coll1.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1597649 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1597650 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/coll1.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1597652 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1597653 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1597655 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/coll1.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1597657 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/coll1.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1597658 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1597660 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/coll1.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1597662 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/coll1.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1597664 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/coll1.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1597665 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1597667 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/coll1.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1597668 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/coll1.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1597670 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/coll1.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1597672 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/coll1.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1597673 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/coll1.AUTOCREATED/synonyms.txt
[junit4] 2> 1597674 INFO (OverseerThreadFactory-17871-thread-1-processing-n:127.0.0.1:42575_solr) [n:127.0.0.1:42575_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/coll1.AUTOCREATED/stopwords.txt
[junit4] 2> 1597788 INFO (OverseerStateUpdate-72238514515476486-127.0.0.1:42575_solr-n_0000000000) [n:127.0.0.1:42575_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"coll1_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:33625_solr",
[junit4] 2> "base_url":"https://127.0.0.1:33625/solr",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1597791 INFO (OverseerStateUpdate-72238514515476486-127.0.0.1:42575_solr-n_0000000000) [n:127.0.0.1:42575_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"coll1_shard2_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:42575_solr",
[junit4] 2> "base_url":"https://127.0.0.1:42575/solr",
[junit4] 2> "collection":"coll1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1598003 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr x:coll1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard1_replica_n1&action=CREATE&numShards=2&collection=coll1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1598029 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1598029 WARN (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 1598032 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr x:coll1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=coll1.AUTOCREATED&newCollection=true&name=coll1_shard2_replica_n2&action=CREATE&numShards=2&collection=coll1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1598032 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr x:coll1_shard2_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1598037 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1598044 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1598044 WARN (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 1598048 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1598083 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1598099 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1598125 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard1_replica_n1' using configuration from configset coll1.AUTOCREATED, trusted=true
[junit4] 2> 1598126 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrCore [[coll1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node1/coll1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node1/coll1_shard1_replica_n1/data/]
[junit4] 2> 1598161 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'coll1_shard2_replica_n2' using configuration from configset coll1.AUTOCREATED, trusted=true
[junit4] 2> 1598162 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.c.SolrCore [[coll1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node2/coll1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.ZkFailoverTest_815003D8E44CA618-001/tempDir-001/node2/coll1_shard2_replica_n2/data/]
[junit4] 2> 1598198 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1598198 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1598199 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1598199 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1598202 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1.AUTOCREATED
[junit4] 2> 1598203 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1.AUTOCREATED
[junit4] 2> 1598203 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 1598203 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1598203 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1598203 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
[junit4] 2> 1598204 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1598204 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1701256646929416192
[junit4] 2> 1598206 INFO (searcherExecutor-17897-thread-1-processing-n:127.0.0.1:33625_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node3) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1598206 INFO (searcherExecutor-17897-thread-1-processing-n:127.0.0.1:33625_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node3) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1598206 INFO (searcherExecutor-17897-thread-1-processing-n:127.0.0.1:33625_solr x:coll1_shard1_replica_n1 c:coll1 s:shard1 r:core_node3) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SolrCore [coll1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1598210 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/coll1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1598210 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/coll1/leaders/shard1
[junit4] 2> 1598214 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1598214 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1598214 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33625/solr/coll1_shard1_replica_n1/
[junit4] 2> 1598214 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1598215 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:33625/solr/coll1_shard1_replica_n1/ has no replicas
[junit4] 2> 1598215 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/coll1/leaders/shard1/leader after winning as /collections/coll1/leader_elect/shard1/election/72238514515476488-core_node3-n_0000000000
[junit4] 2> 1598217 INFO (qtp1028612723-30383) [n:127.0.0.1:33625_solr c:coll1 s:shard1 r:core_node3 x:coll1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33625/solr/coll1_shard1_replica_n1/ shard1
[junit4] 2> 1598226 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1598226 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1598227 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1598227 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1598230 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/coll1.AUTOCREATED
[junit4] 2> 1598230 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/coll1.AUTOCREATED
[junit4] 2> 1598230 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/coll1.AUTOCREATED/managed-schema
[junit4] 2> 1598231 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1598231 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1598231 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
[junit4] 2> 1598232 INFO (qtp1326650827-30390) [n:127.0.0.1:42575_solr c:coll1 s:shard2 r:core_node4 x:coll1_shard2_replica_n2 ] o.a.s.h.Rep
[...truncated too long message...]
va:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 16) Thread[id=30571, name=ZkTestServer Run Thread, state=WAITING, group=TGRP-ZkFailoverTest]
[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:929)
[junit4] > at org.apache.solr.cloud.ZkTestServer$ZKServerMain.runFromConfig(ZkTestServer.java:349)
[junit4] > at org.apache.solr.cloud.ZkTestServer$2.run(ZkTestServer.java:580)
[junit4] > at __randomizedtesting.SeedInfo.seed([815003D8E44CA618]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=30574, name=NIOServerCxnFactory.AcceptThread:0.0.0.0/0.0.0.0:45121, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] > at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.select(NIOServerCnxnFactory.java:219)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory$AcceptThread.run(NIOServerCnxnFactory.java:195)
[junit4] > 2) Thread[id=30590, name=NIOWorkerThread-6, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 3) Thread[id=30591, name=NIOWorkerThread-7, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 4) Thread[id=30583, name=NIOWorkerThread-3, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 5) Thread[id=30573, name=NIOServerCxnFactory.SelectorThread-0, state=RUNNABLE, group=TGRP-ZkFailoverTest]
[junit4] > at sun.nio.ch.IOUtil.drain(Native Method)
[junit4] > at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelectorImpl.java:104)
[junit4] > at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:86)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:97)
[junit4] > at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:101)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.select(NIOServerCnxnFactory.java:417)
[junit4] > at org.apache.zookeeper.server.NIOServerCnxnFactory$SelectorThread.run(NIOServerCnxnFactory.java:382)
[junit4] > 6) Thread[id=30579, name=NIOWorkerThread-1, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 7) Thread[id=30582, name=NIOWorkerThread-2, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 8) Thread[id=30588, name=NIOWorkerThread-5, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 9) Thread[id=30592, name=NIOWorkerThread-8, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > 10) Thread[id=30587, name=NIOWorkerThread-4, state=WAITING, group=TGRP-ZkFailoverTest]
[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 java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074)
[junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134)
[junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([815003D8E44CA618]:0)
[junit4] Completed [577/939 (1!)] on J2 in 54.07s, 1 test, 3 errors <<< FAILURES!
[...truncated 53685 lines...]