You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/01/18 05:20:41 UTC
[JENKINS] Lucene-Solr-master-MacOSX (64bit/jdk1.8.0) - Build # 4392
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/4392/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseSerialGC
10 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.analytics.legacy.LegacyNoFacetCloudTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.analytics.legacy.LegacyNoFacetCloudTest: 1) Thread[id=1262, name=qtp1610573316-1262, state=TIMED_WAITING, group=TGRP-LegacyNoFacetCloudTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.analytics.legacy.LegacyNoFacetCloudTest:
1) Thread[id=1262, name=qtp1610573316-1262, state=TIMED_WAITING, group=TGRP-LegacyNoFacetCloudTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([4D7B84631397BDC4]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.analytics.legacy.LegacyNoFacetCloudTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=1262, name=qtp1610573316-1262, state=TIMED_WAITING, group=TGRP-LegacyNoFacetCloudTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 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=1262, name=qtp1610573316-1262, state=TIMED_WAITING, group=TGRP-LegacyNoFacetCloudTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([4D7B84631397BDC4]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.ltr.TestLTRWithFacet
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.ltr.TestLTRWithFacet: 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.ltr.TestLTRWithFacet:
1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([19FFC6A77E77A13A]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.ltr.TestLTRWithFacet
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 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=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([19FFC6A77E77A13A]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ShardRoutingTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.ShardRoutingTest: 1) Thread[id=19388, name=qtp282517969-19388, state=TIMED_WAITING, group=TGRP-ShardRoutingTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.ShardRoutingTest:
1) Thread[id=19388, name=qtp282517969-19388, state=TIMED_WAITING, group=TGRP-ShardRoutingTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.ShardRoutingTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=19388, name=qtp282517969-19388, state=TIMED_WAITING, group=TGRP-ShardRoutingTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) 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=19388, name=qtp282517969-19388, state=TIMED_WAITING, group=TGRP-ShardRoutingTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770]:0)
FAILED: org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Expected to see collection awhollynewcollection_0 null Live Nodes: [127.0.0.1:52541_solr, 127.0.0.1:52542_solr, 127.0.0.1:52543_solr, 127.0.0.1:52547_solr] Last available state: DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/2)={ "pullReplicas":"0", "replicationFactor":"3", "shards":{ "shard1":{ "range":"80000000-b332ffff", "state":"active", "replicas":{ "core_node3":{ "core":"awhollynewcollection_0_shard1_replica_n1", "base_url":"http://127.0.0.1:52541/solr", "node_name":"127.0.0.1:52541_solr", "state":"down", "type":"NRT"}, "core_node5":{ "core":"awhollynewcollection_0_shard1_replica_n2", "base_url":"http://127.0.0.1:52542/solr", "node_name":"127.0.0.1:52542_solr", "state":"down", "type":"NRT"}, "core_node7":{ "core":"awhollynewcollection_0_shard1_replica_n4", "base_url":"http://127.0.0.1:52547/solr", "node_name":"127.0.0.1:52547_solr", "state":"down", "type":"NRT"}}}, "shard2":{ "range":"b3330000-e665ffff", "state":"active", "replicas":{ "core_node9":{ "core":"awhollynewcollection_0_shard2_replica_n6", "base_url":"http://127.0.0.1:52543/solr", "node_name":"127.0.0.1:52543_solr", "state":"down", "type":"NRT"}, "core_node11":{ "core":"awhollynewcollection_0_shard2_replica_n8", "base_url":"http://127.0.0.1:52541/solr", "node_name":"127.0.0.1:52541_solr", "state":"down", "type":"NRT"}, "core_node13":{ "core":"awhollynewcollection_0_shard2_replica_n10", "base_url":"http://127.0.0.1:52542/solr", "node_name":"127.0.0.1:52542_solr", "state":"down", "type":"NRT"}}}, "shard3":{ "range":"e6660000-1998ffff", "state":"active", "replicas":{ "core_node17":{ "core":"awhollynewcollection_0_shard3_replica_n12", "base_url":"http://127.0.0.1:52547/solr", "node_name":"127.0.0.1:52547_solr", "state":"down", "type":"NRT"}, "core_node19":{ "core":"awhollynewcollection_0_shard3_replica_n14", "base_url":"http://127.0.0.1:52543/solr", "node_name":"127.0.0.1:52543_solr", "state":"down", "type":"NRT"}, "core_node20":{ "core":"awhollynewcollection_0_shard3_replica_n15", "base_url":"http://127.0.0.1:52541/solr", "node_name":"127.0.0.1:52541_solr", "state":"down", "type":"NRT"}}}, "shard4":{ "range":"19990000-4ccbffff", "state":"active", "replicas":{ "core_node22":{ "core":"awhollynewcollection_0_shard4_replica_n16", "base_url":"http://127.0.0.1:52542/solr", "node_name":"127.0.0.1:52542_solr", "state":"down", "type":"NRT"}, "core_node23":{ "core":"awhollynewcollection_0_shard4_replica_n18", "base_url":"http://127.0.0.1:52547/solr", "node_name":"127.0.0.1:52547_solr", "state":"down", "type":"NRT"}, "core_node25":{ "core":"awhollynewcollection_0_shard4_replica_n21", "base_url":"http://127.0.0.1:52543/solr", "node_name":"127.0.0.1:52543_solr", "state":"down", "type":"NRT"}}}, "shard5":{ "range":"4ccc0000-7fffffff", "state":"active", "replicas":{ "core_node28":{ "core":"awhollynewcollection_0_shard5_replica_n24", "base_url":"http://127.0.0.1:52541/solr", "node_name":"127.0.0.1:52541_solr", "state":"down", "type":"NRT"}, "core_node29":{ "core":"awhollynewcollection_0_shard5_replica_n26", "base_url":"http://127.0.0.1:52542/solr", "node_name":"127.0.0.1:52542_solr", "state":"down", "type":"NRT"}, "core_node30":{ "core":"awhollynewcollection_0_shard5_replica_n27", "base_url":"http://127.0.0.1:52547/solr", "node_name":"127.0.0.1:52547_solr", "state":"down", "type":"NRT"}}}}, "router":{"name":"compositeId"}, "maxShardsPerNode":"4", "autoAddReplicas":"false", "nrtReplicas":"3", "tlogReplicas":"0"}
Stack Trace:
java.lang.AssertionError: Expected to see collection awhollynewcollection_0
null
Live Nodes: [127.0.0.1:52541_solr, 127.0.0.1:52542_solr, 127.0.0.1:52543_solr, 127.0.0.1:52547_solr]
Last available state: DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/2)={
"pullReplicas":"0",
"replicationFactor":"3",
"shards":{
"shard1":{
"range":"80000000-b332ffff",
"state":"active",
"replicas":{
"core_node3":{
"core":"awhollynewcollection_0_shard1_replica_n1",
"base_url":"http://127.0.0.1:52541/solr",
"node_name":"127.0.0.1:52541_solr",
"state":"down",
"type":"NRT"},
"core_node5":{
"core":"awhollynewcollection_0_shard1_replica_n2",
"base_url":"http://127.0.0.1:52542/solr",
"node_name":"127.0.0.1:52542_solr",
"state":"down",
"type":"NRT"},
"core_node7":{
"core":"awhollynewcollection_0_shard1_replica_n4",
"base_url":"http://127.0.0.1:52547/solr",
"node_name":"127.0.0.1:52547_solr",
"state":"down",
"type":"NRT"}}},
"shard2":{
"range":"b3330000-e665ffff",
"state":"active",
"replicas":{
"core_node9":{
"core":"awhollynewcollection_0_shard2_replica_n6",
"base_url":"http://127.0.0.1:52543/solr",
"node_name":"127.0.0.1:52543_solr",
"state":"down",
"type":"NRT"},
"core_node11":{
"core":"awhollynewcollection_0_shard2_replica_n8",
"base_url":"http://127.0.0.1:52541/solr",
"node_name":"127.0.0.1:52541_solr",
"state":"down",
"type":"NRT"},
"core_node13":{
"core":"awhollynewcollection_0_shard2_replica_n10",
"base_url":"http://127.0.0.1:52542/solr",
"node_name":"127.0.0.1:52542_solr",
"state":"down",
"type":"NRT"}}},
"shard3":{
"range":"e6660000-1998ffff",
"state":"active",
"replicas":{
"core_node17":{
"core":"awhollynewcollection_0_shard3_replica_n12",
"base_url":"http://127.0.0.1:52547/solr",
"node_name":"127.0.0.1:52547_solr",
"state":"down",
"type":"NRT"},
"core_node19":{
"core":"awhollynewcollection_0_shard3_replica_n14",
"base_url":"http://127.0.0.1:52543/solr",
"node_name":"127.0.0.1:52543_solr",
"state":"down",
"type":"NRT"},
"core_node20":{
"core":"awhollynewcollection_0_shard3_replica_n15",
"base_url":"http://127.0.0.1:52541/solr",
"node_name":"127.0.0.1:52541_solr",
"state":"down",
"type":"NRT"}}},
"shard4":{
"range":"19990000-4ccbffff",
"state":"active",
"replicas":{
"core_node22":{
"core":"awhollynewcollection_0_shard4_replica_n16",
"base_url":"http://127.0.0.1:52542/solr",
"node_name":"127.0.0.1:52542_solr",
"state":"down",
"type":"NRT"},
"core_node23":{
"core":"awhollynewcollection_0_shard4_replica_n18",
"base_url":"http://127.0.0.1:52547/solr",
"node_name":"127.0.0.1:52547_solr",
"state":"down",
"type":"NRT"},
"core_node25":{
"core":"awhollynewcollection_0_shard4_replica_n21",
"base_url":"http://127.0.0.1:52543/solr",
"node_name":"127.0.0.1:52543_solr",
"state":"down",
"type":"NRT"}}},
"shard5":{
"range":"4ccc0000-7fffffff",
"state":"active",
"replicas":{
"core_node28":{
"core":"awhollynewcollection_0_shard5_replica_n24",
"base_url":"http://127.0.0.1:52541/solr",
"node_name":"127.0.0.1:52541_solr",
"state":"down",
"type":"NRT"},
"core_node29":{
"core":"awhollynewcollection_0_shard5_replica_n26",
"base_url":"http://127.0.0.1:52542/solr",
"node_name":"127.0.0.1:52542_solr",
"state":"down",
"type":"NRT"},
"core_node30":{
"core":"awhollynewcollection_0_shard5_replica_n27",
"base_url":"http://127.0.0.1:52547/solr",
"node_name":"127.0.0.1:52547_solr",
"state":"down",
"type":"NRT"}}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"4",
"autoAddReplicas":"false",
"nrtReplicas":"3",
"tlogReplicas":"0"}
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770:339EBB3E5BE518E5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:269)
at org.apache.solr.cloud.api.collections.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:444)
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.api.collections.CustomCollectionTest.testRouteFieldForImplicitRouter
Error Message:
Collection not found: withShardField
Stack Trace:
org.apache.solr.common.SolrException: Collection not found: withShardField
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770:2EBB2718F12FF880]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:851)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.api.collections.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:142)
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.api.collections.CustomCollectionTest.testRouteFieldForHashRouter
Error Message:
Collection not found: routeFieldColl
Stack Trace:
org.apache.solr.common.SolrException: Collection not found: routeFieldColl
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770:D3DD5157C2B7DC2A]:0)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:851)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.api.collections.CustomCollectionTest.testRouteFieldForHashRouter(CustomCollectionTest.java:167)
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.api.collections.CustomCollectionTest.testCustomCollectionsAPI
Error Message:
Could not find collection : implicitcoll
Stack Trace:
org.apache.solr.common.SolrException: Could not find collection : implicitcoll
at __randomizedtesting.SeedInfo.seed([7BEBCF8A5DD63770:110A41E1604C8108]:0)
at org.apache.solr.common.cloud.ClusterState.getCollection(ClusterState.java:118)
at org.apache.solr.cloud.SolrCloudTestCase.getCollectionState(SolrCloudTestCase.java:247)
at org.apache.solr.cloud.api.collections.CustomCollectionTest.testCustomCollectionsAPI(CustomCollectionTest.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)
Build Log:
[...truncated 12068 lines...]
[junit4] Suite: org.apache.solr.cloud.api.collections.CustomCollectionTest
[junit4] 2> 781529 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/init-core-data-001
[junit4] 2> 781530 WARN (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=37 numCloses=37
[junit4] 2> 781530 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 781534 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 781534 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001
[junit4] 2> 781534 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 781535 INFO (Thread-1119) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 781535 INFO (Thread-1119) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 781538 ERROR (Thread-1119) [ ] 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> 781645 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:64805
[junit4] 2> 781660 INFO (zkConnectionManagerCallback-1762-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781669 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 781670 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 781670 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 781671 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T16:27:37-05:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 781672 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 781672 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 781672 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 781675 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3099b536{/solr,null,AVAILABLE}
[junit4] 2> 781677 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 781677 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 781677 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 781678 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 781678 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 781678 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f7fe606{HTTP/1.1,[http/1.1]}{127.0.0.1:64807}
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-1) [ ] o.e.j.s.Server Started @786056ms
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64807}
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 781679 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 781679 ERROR (jetty-launcher-1759-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@61ab9c04{/solr,null,AVAILABLE}
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-18T04:19:19.627Z
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2aed9d96{/solr,null,AVAILABLE}
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2757a777{HTTP/1.1,[http/1.1]}{127.0.0.1:64808}
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-4) [ ] o.e.j.s.Server Started @786057ms
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64808}
[junit4] 2> 781680 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6aa0cab7{HTTP/1.1,[http/1.1]}{127.0.0.1:64809}
[junit4] 2> 781681 ERROR (jetty-launcher-1759-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-3) [ ] o.e.j.s.Server Started @786058ms
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64809}
[junit4] 2> 781681 ERROR (jetty-launcher-1759-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5cb4d6ca{/solr,null,AVAILABLE}
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 781681 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-18T04:19:19.628Z
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-18T04:19:19.629Z
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@193243ff{HTTP/1.1,[http/1.1]}{127.0.0.1:64810}
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-2) [ ] o.e.j.s.Server Started @786059ms
[junit4] 2> 781682 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64810}
[junit4] 2> 781682 ERROR (jetty-launcher-1759-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 781683 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 781683 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 781683 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 781683 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-18T04:19:19.630Z
[junit4] 2> 781687 INFO (zkConnectionManagerCallback-1766-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781687 INFO (zkConnectionManagerCallback-1764-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781688 INFO (zkConnectionManagerCallback-1768-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781688 INFO (zkConnectionManagerCallback-1770-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781688 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 781689 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 781689 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 781690 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 781726 INFO (jetty-launcher-1759-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64805/solr
[junit4] 2> 781726 INFO (jetty-launcher-1759-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64805/solr
[junit4] 2> 781728 INFO (jetty-launcher-1759-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64805/solr
[junit4] 2> 781733 INFO (zkConnectionManagerCallback-1782-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781734 INFO (zkConnectionManagerCallback-1780-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781737 INFO (zkConnectionManagerCallback-1776-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781739 INFO (jetty-launcher-1759-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64805/solr
[junit4] 2> 781740 INFO (zkConnectionManagerCallback-1785-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781743 INFO (zkConnectionManagerCallback-1790-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781747 INFO (zkConnectionManagerCallback-1787-thread-1-processing-n:127.0.0.1:64808_solr) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781749 INFO (zkConnectionManagerCallback-1794-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781750 INFO (zkConnectionManagerCallback-1792-thread-1-processing-n:127.0.0.1:64807_solr) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 781949 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 781950 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 781951 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64810_solr
[junit4] 2> 781952 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64809_solr
[junit4] 2> 781952 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.c.Overseer Overseer (id=99368904915943432-127.0.0.1:64810_solr-n_0000000000) starting
[junit4] 2> 781954 INFO (zkCallback-1793-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 781954 INFO (zkCallback-1784-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 781972 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64810_solr
[junit4] 2> 781976 INFO (zkCallback-1784-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 781976 INFO (zkCallback-1793-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 781981 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 781986 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 781989 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 781990 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64807_solr
[junit4] 2> 782003 INFO (zkCallback-1791-thread-1-processing-n:127.0.0.1:64807_solr) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 782003 INFO (zkCallback-1784-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 782003 INFO (zkCallback-1793-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 782003 INFO (zkCallback-1786-thread-1-processing-n:127.0.0.1:64808_solr) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 782004 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 782007 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64808_solr
[junit4] 2> 782019 INFO (zkCallback-1784-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 782019 INFO (zkCallback-1786-thread-1-processing-n:127.0.0.1:64808_solr) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 782019 INFO (zkCallback-1793-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 782019 INFO (zkCallback-1791-thread-1-processing-n:127.0.0.1:64807_solr) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 782123 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64807.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782131 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64810.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782141 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64807.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782141 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64807.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782148 INFO (jetty-launcher-1759-thread-1) [n:127.0.0.1:64807_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node1/.
[junit4] 2> 782164 WARN (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.m.r.j.JmxMetricsReporter Unable to register gauge
[junit4] 2> javax.management.InstanceNotFoundException: solr_64810:dom1=solr,dom2=jvm,name0=buffers,name1=mapped,name=MemoryUsed
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getMBean(DefaultMBeanServerInterceptor.java:1095)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:427)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.registerMBean(JmxMetricsReporter.java:530)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter$JmxListener.onGaugeAdded(JmxMetricsReporter.java:565)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.lambda$start$0(JmxMetricsReporter.java:738)
[junit4] 2> at java.util.HashMap.forEach(HashMap.java:1288)
[junit4] 2> at org.apache.solr.metrics.reporters.jmx.JmxMetricsReporter.start(JmxMetricsReporter.java:728)
[junit4] 2> at org.apache.solr.metrics.reporters.SolrJmxReporter.doInit(SolrJmxReporter.java:109)
[junit4] 2> at org.apache.solr.metrics.SolrMetricReporter.init(SolrMetricReporter.java:70)
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporter(SolrMetricManager.java:881)
[junit4] 2> at org.apache.solr.metrics.SolrMetricManager.loadReporters(SolrMetricManager.java:817)
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:551)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.createCoreContainer(SolrDispatchFilter.java:263)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.init(SolrDispatchFilter.java:183)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.initialize(FilterHolder.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.initialize(ServletHandler.java:741)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.updateMappings(ServletHandler.java:1474)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.setFilterMappings(ServletHandler.java:1537)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterMapping(ServletHandler.java:1183)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.addFilterWithMapping(ServletHandler.java:1020)
[junit4] 2> at org.eclipse.jetty.servlet.ServletContextHandler.addFilter(ServletContextHandler.java:447)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$1.lifeCycleStarted(JettySolrRunner.java:306)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.setStarted(AbstractLifeCycle.java:179)
[junit4] 2> at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:69)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.startJettySolrRunner(MiniSolrCloudCluster.java:384)
[junit4] 2> at org.apache.solr.cloud.MiniSolrCloudCluster.lambda$new$0(MiniSolrCloudCluster.java:247)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 782210 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64810.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782210 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64810.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782216 INFO (jetty-launcher-1759-thread-2) [n:127.0.0.1:64810_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node2/.
[junit4] 2> 782222 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64808.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782231 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64809.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782262 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64808.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782262 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64808.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782263 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64809.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782263 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64809.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 782263 INFO (jetty-launcher-1759-thread-4) [n:127.0.0.1:64808_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node4/.
[junit4] 2> 782264 INFO (jetty-launcher-1759-thread-3) [n:127.0.0.1:64809_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node3/.
[junit4] 2> 782378 INFO (zkConnectionManagerCallback-1804-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 782382 INFO (zkConnectionManagerCallback-1808-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 782384 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 782386 INFO (SUITE-CustomCollectionTest-seed#[7BEBCF8A5DD63770]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64805/solr ready
[junit4] 2> 782419 INFO (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[7BEBCF8A5DD63770]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRouteFieldForImplicitRouter
[junit4] 2> 782421 INFO (TEST-CustomCollectionTest.testRouteFieldForImplicitRouter-seed#[7BEBCF8A5DD63770]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 782426 INFO (qtp233935613-6505) [n:127.0.0.1:64808_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params shards=a,b,c,d&replicationFactor=4&collection.configName=conf&maxShardsPerNode=5&router.field=shard_s&name=withShardField&router.name=implicit&nrtReplicas=4&action=CREATE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 782429 INFO (OverseerThreadFactory-2551-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection withShardField
[junit4] 2> 782549 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64807/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782553 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64808/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782557 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64809/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782561 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"a",
[junit4] 2> "core":"withShardField_a_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64810/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782566 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n8",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64807/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782570 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n10",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64808/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782575 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n12",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64809/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782579 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"b",
[junit4] 2> "core":"withShardField_b_replica_n14",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64810/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782583 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n16",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64807/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782587 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n18",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64808/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782591 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n20",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64809/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782595 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"c",
[junit4] 2> "core":"withShardField_c_replica_n22",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64810/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782600 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n24",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64807/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782604 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n26",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64808/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782609 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n28",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64809/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782613 INFO (OverseerStateUpdate-99368904915943432-127.0.0.1:64810_solr-n_0000000000) [n:127.0.0.1:64810_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"withShardField",
[junit4] 2> "shard":"d",
[junit4] 2> "core":"withShardField_d_replica_n30",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64810/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 782828 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n2&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782829 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 782835 INFO (qtp233935613-6514) [n:127.0.0.1:64808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node13&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n10&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782836 INFO (qtp1868134403-6495) [n:127.0.0.1:64807_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n1&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782836 INFO (qtp1868134403-6499) [n:127.0.0.1:64807_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node11&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n8&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782837 INFO (qtp1868134403-6495) [n:127.0.0.1:64807_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 782838 INFO (qtp233935613-6527) [n:127.0.0.1:64808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node29&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n26&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782838 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node21&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n18&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782842 INFO (qtp1868134403-6497) [n:127.0.0.1:64807_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node27&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n24&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782843 INFO (qtp1868134403-6502) [n:127.0.0.1:64807_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node19&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n16&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782850 INFO (qtp864373607-6498) [n:127.0.0.1:64809_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node15&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n12&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782851 INFO (qtp864373607-6498) [n:127.0.0.1:64809_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 782851 INFO (qtp864373607-6508) [n:127.0.0.1:64809_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n4&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782856 INFO (qtp1160863973-6500) [n:127.0.0.1:64810_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node9&collection.configName=conf&newCollection=true&name=withShardField_a_replica_n6&action=CREATE&numShards=4&collection=withShardField&shard=a&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782858 INFO (qtp1160863973-6500) [n:127.0.0.1:64810_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 782858 INFO (qtp864373607-6507) [n:127.0.0.1:64809_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node23&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n20&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782859 INFO (qtp864373607-6511) [n:127.0.0.1:64809_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node31&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n28&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782868 INFO (qtp1160863973-6523) [n:127.0.0.1:64810_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node17&collection.configName=conf&newCollection=true&name=withShardField_b_replica_n14&action=CREATE&numShards=4&collection=withShardField&shard=b&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782868 INFO (qtp1160863973-6521) [n:127.0.0.1:64810_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node25&collection.configName=conf&newCollection=true&name=withShardField_c_replica_n22&action=CREATE&numShards=4&collection=withShardField&shard=c&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782868 INFO (qtp1160863973-6515) [n:127.0.0.1:64810_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node32&collection.configName=conf&newCollection=true&name=withShardField_d_replica_n30&action=CREATE&numShards=4&collection=withShardField&shard=d&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 782981 INFO (zkCallback-1793-thread-1-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782981 INFO (zkCallback-1784-thread-1-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782981 INFO (zkCallback-1786-thread-1-processing-n:127.0.0.1:64808_solr) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782981 INFO (zkCallback-1791-thread-1-processing-n:127.0.0.1:64807_solr) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782982 INFO (zkCallback-1793-thread-2-processing-n:127.0.0.1:64809_solr) [n:127.0.0.1:64809_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782982 INFO (zkCallback-1786-thread-2-processing-n:127.0.0.1:64808_solr) [n:127.0.0.1:64808_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782983 INFO (zkCallback-1791-thread-2-processing-n:127.0.0.1:64807_solr) [n:127.0.0.1:64807_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 782984 INFO (zkCallback-1784-thread-2-processing-n:127.0.0.1:64810_solr) [n:127.0.0.1:64810_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/withShardField/state.json] for collection [withShardField] has occurred - updating... (live nodes size: [4])
[junit4] 2> 783868 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783872 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783873 INFO (qtp233935613-6527) [n:127.0.0.1:64808_solr c:withShardField s:d r:core_node29 x:withShardField_d_replica_n26] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783892 INFO (qtp233935613-6514) [n:127.0.0.1:64808_solr c:withShardField s:b r:core_node13 x:withShardField_b_replica_n10] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783894 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.s.IndexSchema [withShardField_a_replica_n2] Schema name=cloud-dynamic
[junit4] 2> 783902 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.s.IndexSchema [withShardField_c_replica_n18] Schema name=cloud-dynamic
[junit4] 2> 783903 INFO (qtp233935613-6527) [n:127.0.0.1:64808_solr c:withShardField s:d r:core_node29 x:withShardField_d_replica_n26] o.a.s.s.IndexSchema [withShardField_d_replica_n26] Schema name=cloud-dynamic
[junit4] 2> 783926 INFO (qtp233935613-6514) [n:127.0.0.1:64808_solr c:withShardField s:b r:core_node13 x:withShardField_b_replica_n10] o.a.s.s.IndexSchema [withShardField_b_replica_n10] Schema name=cloud-dynamic
[junit4] 2> 783944 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 783944 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_a_replica_n2' using configuration from collection withShardField, trusted=true
[junit4] 2> 783944 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64808.solr.core.withShardField.a.replica_n2' (registry 'solr.core.withShardField.a.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 783945 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 783945 INFO (qtp233935613-6517) [n:127.0.0.1:64808_solr c:withShardField s:a r:core_node5 x:withShardField_a_replica_n2] o.a.s.c.SolrCore [[withShardField_a_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node4/withShardField_a_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node4/./withShardField_a_replica_n2/data/]
[junit4] 2> 783962 INFO (qtp1868134403-6495) [n:127.0.0.1:64807_solr c:withShardField s:a r:core_node3 x:withShardField_a_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783969 INFO (qtp1868134403-6502) [n:127.0.0.1:64807_solr c:withShardField s:c r:core_node19 x:withShardField_c_replica_n16] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783970 INFO (qtp1160863973-6500) [n:127.0.0.1:64810_solr c:withShardField s:a r:core_node9 x:withShardField_a_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783974 INFO (qtp864373607-6508) [n:127.0.0.1:64809_solr c:withShardField s:a r:core_node7 x:withShardField_a_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783975 INFO (qtp864373607-6498) [n:127.0.0.1:64809_solr c:withShardField s:b r:core_node15 x:withShardField_b_replica_n12] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783994 INFO (qtp864373607-6507) [n:127.0.0.1:64809_solr c:withShardField s:c r:core_node23 x:withShardField_c_replica_n20] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 783999 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.s.IndexSchema Loaded schema cloud-dynamic/1.6 with uniqueid field id
[junit4] 2> 783999 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.c.CoreContainer Creating SolrCore 'withShardField_c_replica_n18' using configuration from collection withShardField, trusted=true
[junit4] 2> 783999 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64808.solr.core.withShardField.c.replica_n18' (registry 'solr.core.withShardField.c.replica_n18') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29f9fd6
[junit4] 2> 784000 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 784000 INFO (qtp233935613-6518) [n:127.0.0.1:64808_solr c:withShardField s:c r:core_node21 x:withShardField_c_replica_n18] o.a.s.c.SolrCore [[withShardField_c_replica_n18] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node4/withShardField_c_replica_n18], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.api.collections.CustomCollectionTest_7BEBCF8A5DD63770-001/tempDir-001/node4/./withShardField_c_replica_n18/data/]
[junit4] 2> 784001 INFO (qtp1868134403-6502) [n:127.0.0.1:64807_solr c:withShardField s:c r:core_node19 x:withShardField_c_replica_n16] o.a.s.s.IndexSchema [withShardField_c_replica_n16] Schema name=cloud-dynamic
[junit4] 2> 784003 INFO (qtp1868134403-6497) [n:127.0.0.1:64807_solr c:withShardField s:d r:core_node27 x:withShardField_d_replica_n24] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 784005 INFO (qtp864373607-6511) [n:127.0.0.1:64809_solr c
[...truncated too long message...]
2> 60442 INFO (coreCloseExecutor-259-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@43a6c06d
[junit4] 2> 60442 INFO (coreCloseExecutor-259-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1135001709
[junit4] 2> 60446 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@32a41070{/solr,null,UNAVAILABLE}
[junit4] 2> 60447 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 90460 WARN (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp2055074640{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop Thread[qtp2055074640-317,5,TGRP-TestLTRWithFacet]
[junit4] 2> 90464 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=250187712
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12754c79: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / null
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2cbe3bc7: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / null
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 90465 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36406af6: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / null
[junit4] 2> 90480 INFO (coreCloseExecutor-261-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@622cfd5b
[junit4] 2> 90480 INFO (coreCloseExecutor-261-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1647115611
[junit4] 2> 90480 INFO (coreCloseExecutor-261-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2f2a5cb9: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / null
[junit4] 2> 90486 INFO (SUITE-TestLTRWithFacet-seed#[19FFC6A77E77A13A]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Jan 18, 2018 5:01:22 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Jan 18, 2018 5:01:42 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.ltr.TestLTRWithFacet:
[junit4] 2> 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Jan 18, 2018 5:01:42 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
[junit4] 2> Jan 18, 2018 5:01:45 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {description=PostingsFormat(name=Direct), id=FSTOrd50, text=Lucene50(blocksize=128), title=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=367, maxMBSortInHeap=6.129912479169482, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@74e3350f), locale=vi-VN, timezone=Indian/Cocos
[junit4] 2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=2,free=30126792,total=102834176
[junit4] 2> NOTE: All tests run in this JVM: [TestModelManager, TestRankingFeature, TestFeatureExtractionFromMultipleSegments, TestWrapperModel, TestSelectiveWeightCreation, TestExternalFeatures, TestFieldLengthFeature, TestValueFeature, TestFilterSolrFeature, TestLTRQParserExplain, TestLTRReRankingPipeline, TestLTRScoringQuery, TestLTRWithFacet]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestLTRWithFacet -Dtests.seed=19FFC6A77E77A13A -Dtests.slow=true -Dtests.locale=vi-VN -Dtests.timezone=Indian/Cocos -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestLTRWithFacet (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.ltr.TestLTRWithFacet:
[junit4] > 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([19FFC6A77E77A13A]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=317, name=qtp2055074640-317, state=TIMED_WAITING, group=TGRP-TestLTRWithFacet]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([19FFC6A77E77A13A]:0)
[junit4] Completed [35/35 (1!)] on J1 in 54.38s, 1 test, 2 errors <<< FAILURES!
[...truncated 40692 lines...]