Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/269/
6 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPISolrJTest.testCreateWithDefaultConfigSet Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([BFB71A43A1F8089C:F11CBB04FE47726F]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.CollectionsAPISolrJTest.testCreateWithDefaultConfigSet(CollectionsAPISolrJTest.java:70) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.TestSkipOverseerOperations.testSkipLeaderOperations Error Message: Expected 2x1 for collection: collection1 null Live Nodes: [127.0.0.1:34873_solr, 127.0.0.1:39241_solr, 127.0.0.1:45364_solr] Last available state: null Stack Trace: java.lang.AssertionError: Expected 2x1 for collection: collection1 null Live Nodes: [127.0.0.1:34873_solr, 127.0.0.1:39241_solr, 127.0.0.1:45364_solr] Last available state: null at __randomizedtesting.SeedInfo.seed([BFB71A43A1F8089C:4F5DC9BF7EDB78F6]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269) at org.apache.solr.cloud.TestSkipOverseerOperations.testSkipLeaderOperations(TestSkipOverseerOperations.java:69) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations Error Message: Expected 2x2 for collection: collection2 null Live Nodes: [127.0.0.1:34873_solr, 127.0.0.1:39241_solr, 127.0.0.1:45364_solr] Last available state: null Stack Trace: java.lang.AssertionError: Expected 2x2 for collection: collection2 null Live Nodes: [127.0.0.1:34873_solr, 127.0.0.1:39241_solr, 127.0.0.1:45364_solr] Last available state: null at __randomizedtesting.SeedInfo.seed([BFB71A43A1F8089C:782568055CB4B34A]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269) at org.apache.solr.cloud.TestSkipOverseerOperations.testSkipDownOperations(TestSkipOverseerOperations.java:104) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) FAILED: org.apache.solr.client.solrj.io.graph.GraphExpressionTest.testGatherNodesStream Error Message: java.util.concurrent.ExecutionException: java.lang.RuntimeException: java.io.IOException: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 Stack Trace: java.lang.RuntimeException: java.util.concurrent.ExecutionException: java.lang.RuntimeException: java.io.IOException: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 at __randomizedtesting.SeedInfo.seed([CBB332FAB62E031D:42E4352F5830759D]:0) at org.apache.solr.client.solrj.io.graph.GatherNodesStream.read(GatherNodesStream.java:605) at org.apache.solr.client.solrj.io.graph.GraphExpressionTest.getTuples(GraphExpressionTest.java:932) at org.apache.solr.client.solrj.io.graph.GraphExpressionTest.testGatherNodesStream(GraphExpressionTest.java:380) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:748) Caused by: java.util.concurrent.ExecutionException: java.lang.RuntimeException: java.io.IOException: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 at java.util.concurrent.FutureTask.report(FutureTask.java:122) at java.util.concurrent.FutureTask.get(FutureTask.java:192) at org.apache.solr.client.solrj.io.graph.GatherNodesStream.read(GatherNodesStream.java:577) ... 41 more Caused by: java.lang.RuntimeException: java.io.IOException: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 at org.apache.solr.client.solrj.io.graph.GatherNodesStream$JoinRunner.call(GatherNodesStream.java:492) at org.apache.solr.client.solrj.io.graph.GatherNodesStream$JoinRunner.call(GatherNodesStream.java:411) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ... 1 more Caused by: java.io.IOException: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 at org.apache.solr.client.solrj.io.stream.CloudSolrStream.constructStreams(CloudSolrStream.java:378) at org.apache.solr.client.solrj.io.stream.CloudSolrStream.open(CloudSolrStream.java:274) at org.apache.solr.client.solrj.io.stream.PushBackStream.open(PushBackStream.java:71) at org.apache.solr.client.solrj.io.stream.ReducerStream.open(ReducerStream.java:200) at org.apache.solr.client.solrj.io.stream.UniqueStream.open(UniqueStream.java:151) at org.apache.solr.client.solrj.io.graph.GatherNodesStream$JoinRunner.call(GatherNodesStream.java:481) ... 6 more Caused by: org.apache.solr.common.SolrException: Could not load collection from ZK: collection1 at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1122) at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:647) at org.apache.solr.common.cloud.ClusterState.getCollectionsMap(ClusterState.java:146) at org.apache.solr.client.solrj.io.stream.CloudSolrStream.getSlices(CloudSolrStream.java:331) at org.apache.solr.client.solrj.io.stream.TupleStream.getShards(TupleStream.java:141) at org.apache.solr.client.solrj.io.stream.CloudSolrStream.constructStreams(CloudSolrStream.java:363) ... 11 more Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/collection1/state.json at org.apache.zookeeper.KeeperException.create(KeeperException.java:127) at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212) at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:332) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:332) at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1134) at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1120) ... 16 more FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.io.graph.GraphExpressionTest Error Message: 10 threads leaked from SUITE scope at org.apache.solr.client.solrj.io.graph.GraphExpressionTest: 1) Thread[id=1824, name=zkCallback-410-thread-1-EventThread, state=WAITING, group=TGRP-GraphExpressionTest] 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.ClientCnxn$EventThread.run(ClientCnxn.java:501) 2) Thread[id=1819, name=zkCallback-410-thread-2, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1822, name=zkCallback-410-thread-5, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1823, name=zkCallback-410-thread-1-SendThread(127.0.0.1:39305), state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) 5) Thread[id=1799, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 6) Thread[id=1820, name=zkCallback-410-thread-3, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1798, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=1821, name=zkCallback-410-thread-4, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1795, name=zkCallback-410-thread-1, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1792, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 10 threads leaked from SUITE scope at org.apache.solr.client.solrj.io.graph.GraphExpressionTest: 1) Thread[id=1824, name=zkCallback-410-thread-1-EventThread, state=WAITING, group=TGRP-GraphExpressionTest] 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.ClientCnxn$EventThread.run(ClientCnxn.java:501) 2) Thread[id=1819, name=zkCallback-410-thread-2, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1822, name=zkCallback-410-thread-5, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1823, name=zkCallback-410-thread-1-SendThread(127.0.0.1:39305), state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) 5) Thread[id=1799, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 6) Thread[id=1820, name=zkCallback-410-thread-3, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1798, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) 8) Thread[id=1821, name=zkCallback-410-thread-4, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1795, name=zkCallback-410-thread-1, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1792, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) at java.lang.Thread.run(Thread.java:748) at __randomizedtesting.SeedInfo.seed([CBB332FAB62E031D]:0) FAILED: junit.framework.TestSuite.org.apache.solr.client.solrj.io.graph.GraphExpressionTest Error Message: There are still zombie threads that couldn't be terminated: 1) Thread[id=1819, name=zkCallback-410-thread-2, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 2) Thread[id=1822, name=zkCallback-410-thread-5, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1823, name=zkCallback-410-thread-1-SendThread(127.0.0.1:39305), state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) 4) Thread[id=1820, name=zkCallback-410-thread-3, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1821, name=zkCallback-410-thread-4, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=1795, name=zkCallback-410-thread-1, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1819, name=zkCallback-410-thread-2, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 2) Thread[id=1822, name=zkCallback-410-thread-5, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1823, name=zkCallback-410-thread-1-SendThread(127.0.0.1:39305), state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at java.lang.Thread.sleep(Native Method) at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) 4) Thread[id=1820, name=zkCallback-410-thread-3, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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=1821, name=zkCallback-410-thread-4, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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) 6) Thread[id=1795, name=zkCallback-410-thread-1, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) 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([CBB332FAB62E031D]:0) Build Log: [...truncated 13294 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPISolrJTest [junit4] 2> 4903000 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/init-core-data-001 [junit4] 2> 4903000 WARN (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=18 numCloses=18 [junit4] 2> 4903000 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 4903035 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) [junit4] 2> 4903035 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001 [junit4] 2> 4903035 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4903050 INFO (Thread-3194) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4903050 INFO (Thread-3194) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4903052 ERROR (Thread-3194) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 4903160 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:41550 [junit4] 2> 4903292 INFO (jetty-launcher-2348-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 4903293 INFO (jetty-launcher-2348-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 4903343 INFO (jetty-launcher-2348-thread-4) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 4903343 INFO (jetty-launcher-2348-thread-3) [ ] o.e.j.s.Server jetty-9.3.20.v20170531 [junit4] 2> 4903370 INFO (jetty-launcher-2348-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14fd096f{/solr,null,AVAILABLE} [junit4] 2> 4903437 INFO (jetty-launcher-2348-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ed3b326{/solr,null,AVAILABLE} [junit4] 2> 4903438 INFO (jetty-launcher-2348-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6400a0d3{HTTP/1.1,[http/1.1]}{127.0.0.1:40221} [junit4] 2> 4903438 INFO (jetty-launcher-2348-thread-3) [ ] o.e.j.s.Server Started @4917852ms [junit4] 2> 4903438 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40221} [junit4] 2> 4903438 ERROR (jetty-launcher-2348-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4903438 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 4903438 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4903439 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4903439 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-05T00:27:35.962Z [junit4] 2> 4903440 INFO (jetty-launcher-2348-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@594dc6b3{/solr,null,AVAILABLE} [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8ffe142{HTTP/1.1,[http/1.1]}{127.0.0.1:40155} [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.e.j.s.Server Started @4917855ms [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40155} [junit4] 2> 4903441 ERROR (jetty-launcher-2348-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4903441 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-05T00:27:35.964Z [junit4] 2> 4903443 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ffd77aa{/solr,null,AVAILABLE} [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@732b5690{HTTP/1.1,[http/1.1]}{127.0.0.1:44943} [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.e.j.s.Server Started @4917875ms [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44943} [junit4] 2> 4903461 ERROR (jetty-launcher-2348-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4903461 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-05T00:27:35.984Z [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5180f5a6{HTTP/1.1,[http/1.1]}{127.0.0.1:44415} [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.e.j.s.Server Started @4917891ms [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44415} [junit4] 2> 4903477 ERROR (jetty-launcher-2348-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0 [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4903477 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-05T00:27:36Z [junit4] 2> 4903576 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 4903607 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 4903770 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 4903811 INFO (jetty-launcher-2348-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41550/solr [junit4] 2> 4903832 INFO (jetty-launcher-2348-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41550/solr [junit4] 2> 4903852 INFO (jetty-launcher-2348-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41550/solr [junit4] 2> 4903900 INFO (jetty-launcher-2348-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41550/solr [junit4] 2> 4904209 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4904209 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4904210 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44943_solr [junit4] 2> 4904211 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.c.Overseer Overseer (id=99118852051697676-127.0.0.1:44943_solr-n_0000000000) starting [junit4] 2> 4904212 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44415_solr [junit4] 2> 4904247 INFO (zkCallback-2372-thread-1-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4904294 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44943_solr [junit4] 2> 4904335 INFO (zkCallback-2372-thread-1-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 4904445 INFO (zkCallback-2370-thread-1-processing-n:127.0.0.1:44415_solr) [n:127.0.0.1:44415_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 4904650 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44943.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904723 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44943.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904724 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44943.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904725 INFO (jetty-launcher-2348-thread-2) [n:127.0.0.1:44943_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node2/. [junit4] 2> 4904802 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44415.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904875 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44415.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904875 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44415.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4904909 INFO (jetty-launcher-2348-thread-1) [n:127.0.0.1:44415_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node1/. [junit4] 2> 4905087 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 4905100 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4905102 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40155_solr [junit4] 2> 4905144 INFO (zkCallback-2369-thread-1-processing-n:127.0.0.1:40155_solr) [n:127.0.0.1:40155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4905146 INFO (zkCallback-2370-thread-1-processing-n:127.0.0.1:44415_solr) [n:127.0.0.1:44415_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4905217 INFO (zkCallback-2372-thread-2-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4905236 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 4905237 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4905251 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40221_solr [junit4] 2> 4905290 INFO (zkCallback-2369-thread-1-processing-n:127.0.0.1:40155_solr) [n:127.0.0.1:40155_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4905327 INFO (zkCallback-2362-thread-1-processing-n:127.0.0.1:40221_solr) [n:127.0.0.1:40221_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4905330 INFO (zkCallback-2370-thread-1-processing-n:127.0.0.1:44415_solr) [n:127.0.0.1:44415_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4905331 INFO (zkCallback-2372-thread-1-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 4905475 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40155.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905547 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40155.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905548 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40155.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905549 INFO (jetty-launcher-2348-thread-4) [n:127.0.0.1:40155_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node4/. [junit4] 2> 4905668 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40221.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905984 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40221.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905984 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40221.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4905985 INFO (jetty-launcher-2348-thread-3) [n:127.0.0.1:40221_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node3/. [junit4] 2> 4906394 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 4906395 INFO (SUITE-CollectionsAPISolrJTest-seed#[BFB71A43A1F8089C]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41550/solr ready [junit4] 2> 4906521 INFO (TEST-CollectionsAPISolrJTest.testCreateAndDeleteShard-seed#[BFB71A43A1F8089C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateAndDeleteShard [junit4] 2> 4906576 INFO (qtp555254574-14586) [n:127.0.0.1:44943_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=1&shards=shardA,shardB&replicationFactor=1&collection.configName=conf&maxShardsPerNode=3&name=solrj_implicit&router.name=implicit&nrtReplicas=1&action=CREATE&tlogReplicas=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 4906592 INFO (OverseerThreadFactory-6762-thread-1-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.CreateCollectionCmd Create collection solrj_implicit [junit4] 2> 4906878 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardA", [junit4] 2> "core":"solrj_implicit_shardA_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44415/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4906880 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardA", [junit4] 2> "core":"solrj_implicit_shardA_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44943/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4906911 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardA", [junit4] 2> "core":"solrj_implicit_shardA_replica_p4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40221/solr", [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4906912 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardB", [junit4] 2> "core":"solrj_implicit_shardB_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:40155/solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4906990 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardB", [junit4] 2> "core":"solrj_implicit_shardB_replica_t9", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44415/solr", [junit4] 2> "type":"TLOG", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4906991 INFO (OverseerStateUpdate-99118852051697676-127.0.0.1:44943_solr-n_0000000000) [n:127.0.0.1:44943_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"solrj_implicit", [junit4] 2> "shard":"shardB", [junit4] 2> "core":"solrj_implicit_shardB_replica_p10", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:44943/solr", [junit4] 2> "type":"PULL", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 4907170 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=solrj_implicit_shardB_replica_t9&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardB&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 4907170 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 4907185 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=solrj_implicit_shardA_replica_n1&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardA&wt=javabin&version=2&replicaType=NRT [junit4] 2> 4907186 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=solrj_implicit_shardA_replica_p4&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardA&wt=javabin&version=2&replicaType=PULL [junit4] 2> 4907226 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node12&collection.configName=conf&newCollection=true&name=solrj_implicit_shardB_replica_p10&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardB&wt=javabin&version=2&replicaType=PULL [junit4] 2> 4907227 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 4907275 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=solrj_implicit_shardB_replica_n6&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardB&wt=javabin&version=2&replicaType=NRT [junit4] 2> 4907276 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 4907296 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 4907300 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=solrj_implicit_shardA_replica_t2&action=CREATE&numShards=2&collection=solrj_implicit&shard=shardA&wt=javabin&version=2&replicaType=TLOG [junit4] 2> 4907523 INFO (zkCallback-2370-thread-1-processing-n:127.0.0.1:44415_solr) [n:127.0.0.1:44415_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907524 INFO (zkCallback-2372-thread-1-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907525 INFO (zkCallback-2372-thread-2-processing-n:127.0.0.1:44943_solr) [n:127.0.0.1:44943_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907535 INFO (zkCallback-2370-thread-2-processing-n:127.0.0.1:44415_solr) [n:127.0.0.1:44415_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907540 INFO (zkCallback-2369-thread-1-processing-n:127.0.0.1:40155_solr) [n:127.0.0.1:40155_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907540 INFO (zkCallback-2362-thread-1-processing-n:127.0.0.1:40221_solr) [n:127.0.0.1:40221_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907560 INFO (zkCallback-2369-thread-2-processing-n:127.0.0.1:40155_solr) [n:127.0.0.1:40155_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4907560 INFO (zkCallback-2362-thread-2-processing-n:127.0.0.1:40221_solr) [n:127.0.0.1:40221_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/solrj_implicit/state.json] for collection [solrj_implicit] has occurred - updating... (live nodes size: [4]) [junit4] 2> 4908606 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4908634 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4908649 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.s.IndexSchema [solrj_implicit_shardA_replica_p4] Schema name=minimal [junit4] 2> 4908651 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4908651 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardA_replica_p4' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4908652 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40221.solr.core.solrj_implicit.shardA.replica_p4' (registry 'solr.core.solrj_implicit.shardA.replica_p4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4908652 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4908652 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.SolrCore [[solrj_implicit_shardA_replica_p4] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node3/solrj_implicit_shardA_replica_p4], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node3/./solrj_implicit_shardA_replica_p4/data/] [junit4] 2> 4908671 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4908724 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.s.IndexSchema [solrj_implicit_shardA_replica_n1] Schema name=minimal [junit4] 2> 4908726 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4908726 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardA_replica_n1' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4908736 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44415.solr.core.solrj_implicit.shardA.replica_n1' (registry 'solr.core.solrj_implicit.shardA.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4908736 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4908736 INFO (qtp1248954780-14574) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardA r:core_node3 x:solrj_implicit_shardA_replica_n1] o.a.s.c.SolrCore [[solrj_implicit_shardA_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node1/solrj_implicit_shardA_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node1/./solrj_implicit_shardA_replica_n1/data/] [junit4] 2> 4908757 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.s.IndexSchema [solrj_implicit_shardB_replica_t9] Schema name=minimal [junit4] 2> 4908896 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4908896 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardB_replica_t9' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4908896 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44415.solr.core.solrj_implicit.shardB.replica_t9' (registry 'solr.core.solrj_implicit.shardB.replica_t9') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4908896 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4908897 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.c.SolrCore [[solrj_implicit_shardB_replica_t9] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node1/solrj_implicit_shardB_replica_t9], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node1/./solrj_implicit_shardB_replica_t9/data/] [junit4] 2> 4908935 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4908944 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4908944 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0 [junit4] 2> 4909015 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.s.IndexSchema [solrj_implicit_shardA_replica_t2] Schema name=minimal [junit4] 2> 4909017 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4909017 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardA_replica_t2' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4909018 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44943.solr.core.solrj_implicit.shardA.replica_t2' (registry 'solr.core.solrj_implicit.shardA.replica_t2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4909018 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4909018 INFO (qtp555254574-14588) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardA r:core_node5 x:solrj_implicit_shardA_replica_t2] o.a.s.c.SolrCore [[solrj_implicit_shardA_replica_t2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node2/solrj_implicit_shardA_replica_t2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node2/./solrj_implicit_shardA_replica_t2/data/] [junit4] 2> 4909027 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.s.IndexSchema [solrj_implicit_shardB_replica_p10] Schema name=minimal [junit4] 2> 4909045 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.s.IndexSchema [solrj_implicit_shardB_replica_n6] Schema name=minimal [junit4] 2> 4909047 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4909047 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardB_replica_n6' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4909047 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40155.solr.core.solrj_implicit.shardB.replica_n6' (registry 'solr.core.solrj_implicit.shardB.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4909051 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4909051 INFO (qtp1231705166-14606) [n:127.0.0.1:40155_solr c:solrj_implicit s:shardB r:core_node8 x:solrj_implicit_shardB_replica_n6] o.a.s.c.SolrCore [[solrj_implicit_shardB_replica_n6] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node4/solrj_implicit_shardB_replica_n6], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node4/./solrj_implicit_shardB_replica_n6/data/] [junit4] 2> 4909070 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 4909125 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.c.CoreContainer Creating SolrCore 'solrj_implicit_shardB_replica_p10' using configuration from collection solrj_implicit, trusted=true [junit4] 2> 4909125 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44943.solr.core.solrj_implicit.shardB.replica_p10' (registry 'solr.core.solrj_implicit.shardB.replica_p10') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437481b4 [junit4] 2> 4909125 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4909125 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.c.SolrCore [[solrj_implicit_shardB_replica_p10] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node2/solrj_implicit_shardB_replica_p10], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPISolrJTest_BFB71A43A1F8089C-001/tempDir-001/node2/./solrj_implicit_shardB_replica_p10/data/] [junit4] 2> 4909251 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4909251 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4909252 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.s.SolrIndexSearcher Opening [Searcher@2ec401ae[solrj_implicit_shardA_replica_p4] main] [junit4] 2> 4909307 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 4909324 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 4909324 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 4909325 INFO (searcherExecutor-6771-thread-1-processing-n:127.0.0.1:40221_solr x:solrj_implicit_shardA_replica_p4 s:shardA c:solrj_implicit r:core_node7) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.SolrCore [solrj_implicit_shardA_replica_p4] Registered new searcher Searcher@2ec401ae[solrj_implicit_shardA_replica_p4] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4909344 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.ZkController solrj_implicit_shardA_replica_p4 starting background replication from leader [junit4] 2> 4909344 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.c.ReplicateFromLeader Will start replication from leader with poll interval: 00:00:03 [junit4] 2> 4909345 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 3000ms [junit4] 2> 4909345 INFO (qtp218139329-14591) [n:127.0.0.1:40221_solr c:solrj_implicit s:shardA r:core_node7 x:solrj_implicit_shardA_replica_p4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 4909783 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4909783 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4909784 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4909784 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4909786 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.s.SolrIndexSearcher Opening [Searcher@39e043e[solrj_implicit_shardB_replica_t9] main] [junit4] 2> 4909800 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 4909800 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 4909801 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 4909801 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4909801 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4909802 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c8c758b[solrj_implicit_shardB_replica_p10] main] [junit4] 2> 4909803 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 4909819 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 4909820 INFO (qtp555254574-14584) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 4909821 INFO (searcherExecutor-6777-thread-1-processing-n:127.0.0.1:44943_solr x:solrj_implicit_shardB_replica_p10 s:shardB c:solrj_implicit r:core_node12) [n:127.0.0.1:44943_solr c:solrj_implicit s:shardB r:core_node12 x:solrj_implicit_shardB_replica_p10] o.a.s.c.SolrCore [solrj_implicit_shardB_replica_p10] Registered new searcher Searcher@2c8c758b[solrj_implicit_shardB_replica_p10] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4909828 INFO (qtp1248954780-14575) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shardB_replica_t9] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1585901639933362176 [junit4] 2> 4909836 INFO (searcherExecutor-6773-thread-1-processing-n:127.0.0.1:44415_solr x:solrj_implicit_shardB_replica_t9 s:shardB c:solrj_implicit r:core_node11) [n:127.0.0.1:44415_solr c:solrj_implicit s:shardB r:core_node11 x:solrj_implicit_shar [...truncated too long message...] a.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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=1792, name=Connection evictor, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.http.impl.client.IdleConnectionEvictor$1.run(IdleConnectionEvictor.java:66) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] > at __randomizedtesting.SeedInfo.seed([CBB332FAB62E031D]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated: [junit4] > 1) Thread[id=1819, name=zkCallback-410-thread-2, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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] > 2) Thread[id=1822, name=zkCallback-410-thread-5, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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=1823, name=zkCallback-410-thread-1-SendThread(127.0.0.1:39305), state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at java.lang.Thread.sleep(Native Method) [junit4] > at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1051) [junit4] > 4) Thread[id=1820, name=zkCallback-410-thread-3, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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=1821, name=zkCallback-410-thread-4, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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] > 6) Thread[id=1795, name=zkCallback-410-thread-1, state=TIMED_WAITING, group=TGRP-GraphExpressionTest] [junit4] > at sun.misc.Unsafe.park(Native Method) [junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.awaitFulfill(SynchronousQueue.java:460) [junit4] > at java.util.concurrent.SynchronousQueue$TransferStack.transfer(SynchronousQueue.java:362) [junit4] > at java.util.concurrent.SynchronousQueue.poll(SynchronousQueue.java:941) [junit4] > at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1073) [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([CBB332FAB62E031D]:0) [junit4] Completed [96/146 (1!)] on J1 in 71.79s, 6 tests, 3 errors <<< FAILURES! [...truncated 33300 lines...] -ecj-javadoc-lint-src: [mkdir] Created dir: /tmp/ecj842221520 [ecj-lint] Compiling 825 source files to /tmp/ecj842221520 [ecj-lint] ---------- [ecj-lint] 1. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/core/src/java/org/apache/lucene/codecs/CodecUtil.java (at line 523) [ecj-lint] throw new CorruptIndexException("misplaced codec footer (file truncated?): length=" + in.length() + " but footerLength==" + footerLength(), input); [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'in' is not closed at this location [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 2. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/core/src/java/org/apache/lucene/index/FrozenBufferedUpdates.java (at line 144) [ecj-lint] RAMOutputStream out = new RAMOutputStream(); [ecj-lint] ^^^ [ecj-lint] Resource leak: 'out' is never closed [ecj-lint] ---------- [ecj-lint] 3. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/core/src/java/org/apache/lucene/index/FrozenBufferedUpdates.java (at line 186) [ecj-lint] RAMOutputStream out = new RAMOutputStream(); [ecj-lint] ^^^ [ecj-lint] Resource leak: 'out' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 4. ERROR in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/core/src/java/org/apache/lucene/index/IndexWriter.java (at line 36) [ecj-lint] import java.util.concurrent.ConcurrentSkipListSet; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import java.util.concurrent.ConcurrentSkipListSet is never used [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 5. WARNING in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/core/src/java/org/apache/lucene/index/ReadersAndUpdates.java (at line 846) [ecj-lint] SegmentReader newReader = new SegmentReader(info, reader, liveDocs, info.info.maxDoc() - delCount); [ecj-lint] ^^^^^^^^^ [ecj-lint] Resource leak: 'newReader' is never closed [ecj-lint] ---------- [ecj-lint] 5 problems (1 error, 4 warnings) BUILD FAILED /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:835: The following error occurred while executing this line: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/build.xml:101: The following error occurred while executing this line: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/build.xml:203: The following error occurred while executing this line: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2105: The following error occurred while executing this line: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/lucene/common-build.xml:2144: Compile failed; see the compiler error output for details. Total time: 276 minutes 24 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org