Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-MacOSX/873/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseSerialGC

2 tests failed.
FAILED:  org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForHashRouter

Error Message:
Collection not found: routeFieldColl

Stack Trace:
org.apache.solr.common.SolrException: Collection not found: routeFieldColl
        at 
__randomizedtesting.SeedInfo.seed([A011E773833B4BD1:82779AE1C5AA08B]:0)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.getCollectionNames(CloudSolrClient.java:1401)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1094)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1073)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at 
org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
        at 
org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForHashRouter(CustomCollectionTest.java:166)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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.TestSizeLimitedDistributedMap.testCleanup

Error Message:
KeeperErrorCode = Session expired for 
/overseer/collection-map-completed/mn-xyz_1466

Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /overseer/collection-map-completed/mn-xyz_1466
        at 
__randomizedtesting.SeedInfo.seed([A011E773833B4BD1:F03BB4DDFA96DB64]:0)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
        at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
        at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
        at 
org.apache.solr.common.cloud.SolrZkClient$10.execute(SolrZkClient.java:527)
        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
        at 
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:524)
        at 
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:480)
        at org.apache.solr.cloud.DistributedMap.put(DistributedMap.java:59)
        at 
org.apache.solr.cloud.SizeLimitedDistributedMap.put(SizeLimitedDistributedMap.java:71)
        at 
org.apache.solr.cloud.TestSizeLimitedDistributedMap.testCleanup(TestSizeLimitedDistributedMap.java:39)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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 11469 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestSizeLimitedDistributedMap
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestSizeLimitedDistributedMap_A011E773833B4BD1-001/init-core-data-001
   [junit4]   2> 777145 WARN  
(SUITE-TestSizeLimitedDistributedMap-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 777145 INFO  
(SUITE-TestSizeLimitedDistributedMap-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 777154 INFO  
(SUITE-TestSizeLimitedDistributedMap-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 777155 INFO  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testCleanup
   [junit4]   2> 777156 INFO  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 777156 INFO  (Thread-969) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 777156 INFO  (Thread-969) [    ] o.a.s.c.ZkTestServer Starting 
server
   [junit4]   2> 777159 ERROR (Thread-969) [    ] 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> 777269 INFO  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:60743
   [junit4]   2> 777303 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15c253c0f030000, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 897435 WARN  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]-SendThread(127.0.0.1:60743))
 [    ] o.a.z.ClientCnxn Client session timed out, have not heard from server 
in 115429ms for sessionid 0x15c253c0f030002
   [junit4]   2> 897435 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn caught end of stream exception
   [junit4]   2> EndOfStreamException: Unable to read additional data from 
client sessionid 0x15c253c0f030002, likely client has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
   [junit4]   2>        at 
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 897541 WARN  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@77f7c16d name: 
ZooKeeperConnection Watcher:127.0.0.1:60743/solr got event WatchedEvent 
state:Disconnected type:None path:null path: null type: None
   [junit4]   2> 897541 WARN  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager zkClient has disconnected
   [junit4]   2> 899196 WARN  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]-SendThread(127.0.0.1:60743))
 [    ] o.a.z.ClientCnxn Unable to reconnect to ZooKeeper service, session 
0x15c253c0f030002 has expired
   [junit4]   2> 899198 WARN  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@77f7c16d name: 
ZooKeeperConnection Watcher:127.0.0.1:60743/solr got event WatchedEvent 
state:Expired type:None path:null path: null type: None
   [junit4]   2> 899198 WARN  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. 
Attempting to reconnect to recover relationship with ZooKeeper...
   [junit4]   2> 899198 WARN  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one...
   [junit4]   2> 899206 INFO  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished.
   [junit4]   2> 899207 INFO  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper
   [junit4]   2> 899207 INFO  (zkCallback-738-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Connected:true
   [junit4]   2> 899304 ERROR 
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
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> 899305 INFO  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:60743 60743
   [junit4]   2> 899330 INFO  (Thread-969) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:60743 60743
   [junit4]   2> 906598 INFO  
(TEST-TestSizeLimitedDistributedMap.testCleanup-seed#[A011E773833B4BD1]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testCleanup
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestSizeLimitedDistributedMap -Dtests.method=testCleanup 
-Dtests.seed=A011E773833B4BD1 -Dtests.slow=true -Dtests.locale=tr 
-Dtests.timezone=Asia/Ust-Nera -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR    129s J0 | TestSizeLimitedDistributedMap.testCleanup <<<
   [junit4]    > Throwable #1: 
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = 
Session expired for /overseer/collection-map-completed/mn-xyz_1466
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A011E773833B4BD1:F03BB4DDFA96DB64]:0)
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
   [junit4]    >        at 
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
   [junit4]    >        at 
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient$10.execute(SolrZkClient.java:527)
   [junit4]    >        at 
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:524)
   [junit4]    >        at 
org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:480)
   [junit4]    >        at 
org.apache.solr.cloud.DistributedMap.put(DistributedMap.java:59)
   [junit4]    >        at 
org.apache.solr.cloud.SizeLimitedDistributedMap.put(SizeLimitedDistributedMap.java:71)
   [junit4]    >        at 
org.apache.solr.cloud.TestSizeLimitedDistributedMap.testCleanup(TestSizeLimitedDistributedMap.java:39)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 906601 INFO  
(SUITE-TestSizeLimitedDistributedMap-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestSizeLimitedDistributedMap_A011E773833B4BD1-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): {}, 
docValues:{}, maxPointsInLeafNode=1584, maxMBSortInHeap=6.6398675965507605, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=tr, 
timezone=Asia/Ust-Nera
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=142853128,total=316907520
   [junit4]   2> NOTE: All tests run in this JVM: 
[UUIDUpdateProcessorFallbackTest, TriLevelCompositeIdRoutingTest, 
TestWordDelimiterFilterFactory, TestHighlightDedupGrouping, 
TestSolrDeletionPolicy1, TestBinaryField, TestConfigSets, TestStressRecovery, 
ShardRoutingTest, TestSerializedLuceneMatchVersion, 
DistributedMLTComponentTest, ClassificationUpdateProcessorFactoryTest, 
TestOnReconnectListenerSupport, TestFaceting, FastVectorHighlighterTest, 
SchemaVersionSpecificBehaviorTest, TestInitParams, TestCustomSort, 
PeerSyncReplicationTest, TestExtendedDismaxParser, 
TestDistributedStatsComponentCardinality, CdcrUpdateLogTest, JavabinLoaderTest, 
TestAuthenticationFramework, HardAutoCommitTest, 
DistributedSpellCheckComponentTest, BigEndianAscendingWordSerializerTest, 
DistribDocExpirationUpdateProcessorTest, SegmentsInfoRequestHandlerTest, 
TestReloadAndDeleteDocs, TestCloudSchemaless, TestSort, 
CurrencyFieldXmlFileTest, OverriddenZkACLAndCredentialsProvidersTest, 
AnalyticsQueryTest, TestHalfAndHalfDocValues, PrimUtilsTest, 
SolrPluginUtilsTest, TestEmbeddedSolrServerConstructors, DocValuesTest, 
TestConfigReload, TestFileDictionaryLookup, ClusterStateUpdateTest, 
TestPushWriter, CursorPagingTest, TestConfigSetsAPIExclusivity, 
TestReRankQParserPlugin, TestFieldTypeCollectionResource, ZkControllerTest, 
ZkNodePropsTest, TestRestoreCore, OutputWriterTest, 
LeaderFailoverAfterPartitionTest, PeerSyncWithIndexFingerprintCachingTest, 
WrapperMergePolicyFactoryTest, RankQueryTest, SynonymTokenizerTest, 
TestPivotHelperCode, SpatialFilterTest, TestNamedUpdateProcessors, 
PeerSyncTest, HttpPartitionTest, DistributedFacetPivotLongTailTest, 
NodeMutatorTest, TestLRUCache, TestAnalyzedSuggestions, 
ParsingFieldUpdateProcessorsTest, TestRawTransformer, 
HdfsRestartWhileUpdatingTest, IndexSchemaRuntimeFieldTest, TestManagedResource, 
TestSweetSpotSimilarityFactory, TestSizeLimitedDistributedMap]
   [junit4] Completed [137/712 (1!)] on J0 in 129.47s, 1 test, 1 error <<< 
FAILURES!

[...truncated 1144 lines...]
   [junit4] Suite: org.apache.solr.cloud.CustomCollectionTest
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/init-core-data-001
   [junit4]   2> 2157498 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2157499 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-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> 2157499 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001
   [junit4]   2> 2157500 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2157500 INFO  (Thread-3218) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2157500 INFO  (Thread-3218) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2157502 ERROR (Thread-3218) [    ] 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> 2157610 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:64138
   [junit4]   2> 2157639 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2157639 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2157639 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2157640 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 2157641 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2715f079{/solr,null,AVAILABLE}
   [junit4]   2> 2157643 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@65058030{HTTP/1.1,[http/1.1]}{127.0.0.1:64140}
   [junit4]   2> 2157643 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.e.j.s.Server Started @2045540ms
   [junit4]   2> 2157643 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64140}
   [junit4]   2> 2157643 ERROR (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2157643 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 2157644 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2157644 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2157644 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-20T10:04:52.492Z
   [junit4]   2> 2157648 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7c82c02a{/solr,null,AVAILABLE}
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@35f9e579{/solr,null,AVAILABLE}
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5d5b6b55{HTTP/1.1,[http/1.1]}{127.0.0.1:64143}
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.e.j.s.Server Started @2045546ms
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64143}
   [junit4]   2> 2157649 ERROR (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@66882ab5{HTTP/1.1,[http/1.1]}{127.0.0.1:64142}
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.e.j.s.Server Started @2045547ms
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64142}
   [junit4]   2> 2157649 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7c33d3b2{/solr,null,AVAILABLE}
   [junit4]   2> 2157650 ERROR (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-20T10:04:52.498Z
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1fa8e29b{HTTP/1.1,[http/1.1]}{127.0.0.1:64144}
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.e.j.s.Server Started @2045547ms
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64144}
   [junit4]   2> 2157650 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-20T10:04:52.498Z
   [junit4]   2> 2157651 ERROR (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2157651 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
6.7.0
   [junit4]   2> 2157651 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2157651 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2157651 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-05-20T10:04:52.499Z
   [junit4]   2> 2157654 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2157655 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2157657 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2157675 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2157675 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2157675 INFO  (jetty-launcher-3668-thread-3) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64138/solr
   [junit4]   2> 2157676 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2157676 INFO  (jetty-launcher-3668-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64138/solr
   [junit4]   2> 2157677 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 2157677 INFO  (jetty-launcher-3668-thread-4) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64138/solr
   [junit4]   2> 2157678 INFO  (jetty-launcher-3668-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64138/solr
   [junit4]   2> 2157714 INFO  (jetty-launcher-3668-thread-3) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2157714 INFO  (jetty-launcher-3668-thread-4) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2157714 INFO  (jetty-launcher-3668-thread-2) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2157714 INFO  (jetty-launcher-3668-thread-1) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2157715 INFO  (jetty-launcher-3668-thread-3) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:64140_solr
   [junit4]   2> 2157716 INFO  (jetty-launcher-3668-thread-4) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64143_solr
   [junit4]   2> 2157716 INFO  (jetty-launcher-3668-thread-2) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64142_solr
   [junit4]   2> 2157716 INFO  (jetty-launcher-3668-thread-1) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64144_solr
   [junit4]   2> 2157717 INFO  (jetty-launcher-3668-thread-3) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.Overseer Overseer 
(id=97994322237849609-127.0.0.1:64140_solr-n_0000000000) starting
   [junit4]   2> 2157720 INFO  
(zkCallback-3688-thread-1-processing-n:127.0.0.1:64142_solr) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2157720 INFO  
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64143_solr) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2157720 INFO  
(zkCallback-3687-thread-1-processing-n:127.0.0.1:64140_solr) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2157720 INFO  
(zkCallback-3689-thread-1-processing-n:127.0.0.1:64144_solr) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (3)
   [junit4]   2> 2157778 INFO  (jetty-launcher-3668-thread-3) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:64140_solr
   [junit4]   2> 2157781 INFO  
(zkCallback-3687-thread-1-processing-n:127.0.0.1:64140_solr) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2157782 INFO  
(zkCallback-3689-thread-1-processing-n:127.0.0.1:64144_solr) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2157782 INFO  
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64143_solr) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2157782 INFO  
(zkCallback-3688-thread-1-processing-n:127.0.0.1:64142_solr) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (3) -> (4)
   [junit4]   2> 2157784 INFO  (jetty-launcher-3668-thread-4) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/.
   [junit4]   2> 2157785 INFO  (jetty-launcher-3668-thread-1) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/.
   [junit4]   2> 2157801 INFO  (jetty-launcher-3668-thread-2) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/.
   [junit4]   2> 2157838 INFO  (jetty-launcher-3668-thread-3) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/.
   [junit4]   2> 2157852 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2157854 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64138/solr ready
   [junit4]   2> 2157882 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[A011E773833B4BD1]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testCustomCollectionsAPI
   [junit4]   2> 2157883 INFO  
(TEST-CustomCollectionTest.testCustomCollectionsAPI-seed#[A011E773833B4BD1]) [  
  ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2157890 INFO  (qtp988599052-17563) [n:127.0.0.1:64142_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
shards=a,b,c&replicationFactor=4&collection.configName=conf&maxShardsPerNode=5&name=implicitcoll&router.name=implicit&action=CREATE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2157895 INFO  
(OverseerThreadFactory-8588-thread-1-processing-n:127.0.0.1:64140_solr) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.CreateCollectionCmd Create collection 
implicitcoll
   [junit4]   2> 2158023 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 2158024 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 2158025 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 2158025 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2158026 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2158024 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica3&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 2158028 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 2158028 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 2158029 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2158030 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 2158030 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica1&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 2158035 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica2&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 2158035 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_c_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=c&wt=javabin&version=2
   [junit4]   2> 2158035 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_a_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=a&wt=javabin&version=2
   [junit4]   2> 2158036 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2158037 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&collection.configName=conf&newCollection=true&name=implicitcoll_b_replica4&action=CREATE&numShards=3&collection=implicitcoll&shard=b&wt=javabin&version=2
   [junit4]   2> 2158216 INFO  
(zkCallback-3689-thread-1-processing-n:127.0.0.1:64144_solr) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2158216 INFO  
(zkCallback-3687-thread-1-processing-n:127.0.0.1:64140_solr) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2158216 INFO  
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64143_solr) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2158216 INFO  
(zkCallback-3688-thread-1-processing-n:127.0.0.1:64142_solr) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2159120 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159121 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159126 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159128 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159142 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159142 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159146 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159161 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159162 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159164 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159180 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159180 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrConfig Using Lucene 
MatchVersion: 6.7.0
   [junit4]   2> 2159185 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.IndexSchema 
[implicitcoll_c_replica3] Schema name=cloud-dynamic
   [junit4]   2> 2159186 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.IndexSchema 
[implicitcoll_b_replica3] Schema name=cloud-dynamic
   [junit4]   2> 2159187 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.IndexSchema 
[implicitcoll_a_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2159203 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.IndexSchema 
[implicitcoll_b_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2159206 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.IndexSchema 
[implicitcoll_b_replica4] Schema name=cloud-dynamic
   [junit4]   2> 2159211 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.IndexSchema 
[implicitcoll_b_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2159203 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.IndexSchema 
[implicitcoll_c_replica1] Schema name=cloud-dynamic
   [junit4]   2> 2159250 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159250 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_b_replica4' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159251 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159251 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrCore 
[[implicitcoll_b_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/implicitcoll_b_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/./implicitcoll_b_replica4/data/]
   [junit4]   2> 2159268 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159268 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_b_replica3' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159269 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159269 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore 
[[implicitcoll_b_replica3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/implicitcoll_b_replica3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/./implicitcoll_b_replica3/data/]
   [junit4]   2> 2159270 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.IndexSchema 
[implicitcoll_a_replica3] Schema name=cloud-dynamic
   [junit4]   2> 2159274 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159274 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_c_replica1' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159274 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159274 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrCore 
[[implicitcoll_c_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/implicitcoll_c_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/./implicitcoll_c_replica1/data/]
   [junit4]   2> 2159278 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema 
[implicitcoll_c_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2159280 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema 
[implicitcoll_a_replica4] Schema name=cloud-dynamic
   [junit4]   2> 2159294 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159294 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_c_replica3' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159294 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159294 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrCore 
[[implicitcoll_c_replica3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/implicitcoll_c_replica3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/./implicitcoll_c_replica3/data/]
   [junit4]   2> 2159301 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159301 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_b_replica2' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159301 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159302 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore 
[[implicitcoll_b_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/implicitcoll_b_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/./implicitcoll_b_replica2/data/]
   [junit4]   2> 2159332 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.IndexSchema 
[implicitcoll_a_replica2] Schema name=cloud-dynamic
   [junit4]   2> 2159331 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159344 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_b_replica1' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159330 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.IndexSchema 
[implicitcoll_c_replica4] Schema name=cloud-dynamic
   [junit4]   2> 2159344 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159344 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrCore 
[[implicitcoll_b_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/implicitcoll_b_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/./implicitcoll_b_replica1/data/]
   [junit4]   2> 2159327 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159345 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_a_replica3' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159345 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159345 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore 
[[implicitcoll_a_replica3] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/implicitcoll_a_replica3],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node2/./implicitcoll_a_replica3/data/]
   [junit4]   2> 2159350 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159350 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_a_replica1' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159350 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159351 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrCore 
[[implicitcoll_a_replica1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/implicitcoll_a_replica1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node1/./implicitcoll_a_replica1/data/]
   [junit4]   2> 2159373 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159373 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_c_replica2' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159374 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159377 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore 
[[implicitcoll_c_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/implicitcoll_c_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/./implicitcoll_c_replica2/data/]
   [junit4]   2> 2159394 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159394 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_a_replica2' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159398 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159398 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore 
[[implicitcoll_a_replica2] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/implicitcoll_a_replica2],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node4/./implicitcoll_a_replica2/data/]
   [junit4]   2> 2159400 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159400 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_a_replica4' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159400 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159401 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore 
[[implicitcoll_a_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/implicitcoll_a_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/./implicitcoll_a_replica4/data/]
   [junit4]   2> 2159402 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.IndexSchema Loaded 
schema cloud-dynamic/1.6 with uniqueid field id
   [junit4]   2> 2159402 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.CoreContainer Creating 
SolrCore 'implicitcoll_c_replica4' using configuration from collection 
implicitcoll, trusted=true
   [junit4]   2> 2159402 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrCore 
solr.RecoveryStrategy.Builder
   [junit4]   2> 2159402 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrCore 
[[implicitcoll_c_replica4] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/implicitcoll_c_replica4],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001/tempDir-001/node3/./implicitcoll_c_replica4/data/]
   [junit4]   2> 2159542 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159542 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159547 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159547 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159549 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159549 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159550 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159550 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159556 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159556 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159557 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159557 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159560 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159560 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159562 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159562 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159582 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159582 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159584 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159584 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159585 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159585 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159587 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@69846dc[implicitcoll_b_replica4] main]
   [junit4]   2> 2159589 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159589 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159591 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@7a060660[implicitcoll_b_replica3] main]
   [junit4]   2> 2159593 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159594 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159594 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159594 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909157999214592
   [junit4]   2> 2159594 INFO  
(searcherExecutor-8596-thread-1-processing-n:127.0.0.1:64142_solr 
x:implicitcoll_b_replica3 s:b c:implicitcoll) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SolrCore 
[implicitcoll_b_replica3] Registered new searcher 
Searcher@7a060660[implicitcoll_b_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159627 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@675eac05[implicitcoll_c_replica3] main]
   [junit4]   2> 2159629 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@714378b[implicitcoll_c_replica1] main]
   [junit4]   2> 2159637 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159637 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@3f6a8294[implicitcoll_a_replica3] main]
   [junit4]   2> 2159637 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159640 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard b: total=4 found=1 timeoutin=9999ms
   [junit4]   2> 2159640 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159640 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159641 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159641 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158048497664
   [junit4]   2> 2159641 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159642 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159642 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159642 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159642 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159642 INFO  
(searcherExecutor-8595-thread-1-processing-n:127.0.0.1:64140_solr 
x:implicitcoll_b_replica4 s:b c:implicitcoll) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.c.SolrCore 
[implicitcoll_b_replica4] Registered new searcher 
Searcher@69846dc[implicitcoll_b_replica4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159648 INFO  (qtp1485613319-17557) [n:127.0.0.1:64140_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica4] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158055837696
   [junit4]   2> 2159650 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@64cea9eb[implicitcoll_b_replica1] main]
   [junit4]   2> 2159651 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159655 INFO  
(searcherExecutor-8601-thread-1-processing-n:127.0.0.1:64142_solr 
x:implicitcoll_a_replica3 s:a c:implicitcoll) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] o.a.s.c.SolrCore 
[implicitcoll_a_replica3] Registered new searcher 
Searcher@3f6a8294[implicitcoll_a_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159656 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159656 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159658 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159658 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159659 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159659 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159660 INFO  (qtp988599052-17564) [n:127.0.0.1:64142_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica3] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard a: total=4 found=1 timeoutin=9999ms
   [junit4]   2> 2159660 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159660 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159660 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159662 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159662 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159661 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159664 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159665 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159665 INFO  
(searcherExecutor-8598-thread-1-processing-n:127.0.0.1:64142_solr 
x:implicitcoll_c_replica3 s:c c:implicitcoll) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.c.SolrCore 
[implicitcoll_c_replica3] Registered new searcher 
Searcher@675eac05[implicitcoll_c_replica3] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159665 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158073663488
   [junit4]   2> 2159666 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159666 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159669 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159669 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159670 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159670 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159670 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159670 INFO  (qtp252958724-17576) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158078906368
   [junit4]   2> 2159671 INFO  
(searcherExecutor-8600-thread-1-processing-n:127.0.0.1:64144_solr 
x:implicitcoll_b_replica1 s:b c:implicitcoll) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica1] o.a.s.c.SolrCore 
[implicitcoll_b_replica1] Registered new searcher 
Searcher@64cea9eb[implicitcoll_b_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159672 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159673 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159673 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159673 INFO  
(searcherExecutor-8597-thread-1-processing-n:127.0.0.1:64144_solr 
x:implicitcoll_c_replica1 s:c c:implicitcoll) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.c.SolrCore 
[implicitcoll_c_replica1] Registered new searcher 
Searcher@714378b[implicitcoll_c_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159674 INFO  (qtp252958724-17574) [n:127.0.0.1:64144_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica1] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158083100672
   [junit4]   2> 2159674 INFO  (qtp988599052-17567) [n:127.0.0.1:64142_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica3] 
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for 
shard c: total=4 found=1 timeoutin=9999ms
   [junit4]   2> 2159676 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@8f732f6[implicitcoll_c_replica4] main]
   [junit4]   2> 2159678 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159679 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159679 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159679 INFO  (qtp1485613319-17554) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158088343552
   [junit4]   2> 2159679 INFO  
(searcherExecutor-8606-thread-1-processing-n:127.0.0.1:64140_solr 
x:implicitcoll_c_replica4 s:c c:implicitcoll) [n:127.0.0.1:64140_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica4] o.a.s.c.SolrCore 
[implicitcoll_c_replica4] Registered new searcher 
Searcher@8f732f6[implicitcoll_c_replica4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159680 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateHandler Using 
UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 2159680 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateLog Initializing 
UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 
maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2159681 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.CommitTracker Hard 
AutoCommit: disabled
   [junit4]   2> 2159681 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.CommitTracker Soft 
AutoCommit: disabled
   [junit4]   2> 2159682 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@6a384daa[implicitcoll_c_replica2] main]
   [junit4]   2> 2159682 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@401c4281[implicitcoll_b_replica2] main]
   [junit4]   2> 2159683 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@19f7ad1d[implicitcoll_a_replica4] main]
   [junit4]   2> 2159683 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159684 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159684 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159684 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159684 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159684 INFO  (qtp1735033610-17585) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158093586432
   [junit4]   2> 2159684 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159685 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159685 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159684 INFO  
(searcherExecutor-8603-thread-1-processing-n:127.0.0.1:64143_solr 
x:implicitcoll_c_replica2 s:c c:implicitcoll) [n:127.0.0.1:64143_solr 
c:implicitcoll s:c  x:implicitcoll_c_replica2] o.a.s.c.SolrCore 
[implicitcoll_c_replica2] Registered new searcher 
Searcher@6a384daa[implicitcoll_c_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159685 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159685 INFO  
(searcherExecutor-8605-thread-1-processing-n:127.0.0.1:64140_solr 
x:implicitcoll_a_replica4 s:a c:implicitcoll) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.c.SolrCore 
[implicitcoll_a_replica4] Registered new searcher 
Searcher@19f7ad1d[implicitcoll_a_replica4] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159685 INFO  (qtp1735033610-17584) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158094635008
   [junit4]   2> 2159685 INFO  
(searcherExecutor-8599-thread-1-processing-n:127.0.0.1:64143_solr 
x:implicitcoll_b_replica2 s:b c:implicitcoll) [n:127.0.0.1:64143_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica2] o.a.s.c.SolrCore 
[implicitcoll_b_replica2] Registered new searcher 
Searcher@401c4281[implicitcoll_b_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159685 INFO  (qtp1485613319-17556) [n:127.0.0.1:64140_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica4] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158094635008
   [junit4]   2> 2159688 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@7741f689[implicitcoll_a_replica1] main]
   [junit4]   2> 2159688 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@3c071d2f[implicitcoll_a_replica2] main]
   [junit4]   2> 2159689 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159690 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.r.ManagedResourceStorage 
Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 2159690 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159690 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159690 INFO  
(searcherExecutor-8602-thread-1-processing-n:127.0.0.1:64144_solr 
x:implicitcoll_a_replica1 s:a c:implicitcoll) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.c.SolrCore 
[implicitcoll_a_replica1] Registered new searcher 
Searcher@7741f689[implicitcoll_a_replica1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159690 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.r.ManagedResourceStorage 
Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2159690 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.h.ReplicationHandler 
Commits will be reserved for  10000
   [junit4]   2> 2159690 INFO  (qtp1735033610-17582) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158099877888
   [junit4]   2> 2159690 INFO  
(searcherExecutor-8604-thread-1-processing-n:127.0.0.1:64143_solr 
x:implicitcoll_a_replica2 s:a c:implicitcoll) [n:127.0.0.1:64143_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica2] o.a.s.c.SolrCore 
[implicitcoll_a_replica2] Registered new searcher 
Searcher@3c071d2f[implicitcoll_a_replica2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2159690 INFO  (qtp252958724-17575) [n:127.0.0.1:64144_solr 
c:implicitcoll s:a  x:implicitcoll_a_replica1] o.a.s.u.UpdateLog Could not find 
max version in index or recent updates, using new clock 1567909158099877888
   [junit4]   2> 2159787 INFO  
(zkCallback-3687-thread-1-processing-n:127.0.0.1:64140_solr) 
[n:127.0.0.1:64140_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2159787 INFO  
(zkCallback-3690-thread-1-processing-n:127.0.0.1:64143_solr) 
[n:127.0.0.1:64143_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2159787 INFO  
(zkCallback-3689-thread-1-processing-n:127.0.0.1:64144_solr) 
[n:127.0.0.1:64144_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2159787 INFO  
(zkCallback-3688-thread-1-processing-n:127.0.0.1:64142_solr) 
[n:127.0.0.1:64142_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/implicitcoll/state.json] for collection [implicitcoll] has 
occurred - updating... (live nodes size: [4])
   [junit4]   2> 2160151 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2160151 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2160151 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.c.SyncStrategy Sync 
replicas to http://127.0.0.1:64142/solr/implicitcoll_b_replica3/
   [junit4]   2> 2160152 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.PeerSync PeerSync: 
core=implicitcoll_b_replica3 url=http://127.0.0.1:64142/solr START 
replicas=[http://127.0.0.1:64143/solr/implicitcoll_b_replica2/, 
http://127.0.0.1:64144/solr/implicitcoll_b_replica1/, 
http://127.0.0.1:64140/solr/implicitcoll_b_replica4/] nUpdates=100
   [junit4]   2> 2160157 INFO  (qtp988599052-17568) [n:127.0.0.1:64142_solr 
c:implicitcoll s:b  x:implicitcoll_b_replica3] o.a.s.u.PeerSync PeerSync: 
core=implicitcoll_b_replica3 url=http://127.0.0.1:64142/solr DONE.  We have no 
versions.  sync failed.
   [junit4]   2> 2160158 INFO  (qtp252958724-17579) [n:127.0.0.1:64144_solr 
c:implicitcoll s:b r:core_node7 x:implicitcoll_b_replica1] o.a.s.c.S.Request 
[implicitcoll_b_replica1]  webapp=/solr path=/get 
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2160158 INFO  (qtp1735033610-17586) [

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

k-3690-thread-2-processing-n:127.0.0.1:64143_solr) [n:127.0.0.1:64143_solr    ] 
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: 
[KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 2209699 INFO  (jetty-closer-3669-thread-4) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2715f079{/solr,null,UNAVAILABLE}
   [junit4]   2> 2209699 INFO  (jetty-closer-3669-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@35f9e579{/solr,null,UNAVAILABLE}
   [junit4]   2> 2209701 ERROR 
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2209701 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:64138 64138
   [junit4]   2> 2209712 INFO  (Thread-3218) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:64138 64138
   [junit4]   2> 2213413 WARN  (Thread-3218) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        12      /solr/configs/conf
   [junit4]   2>        9       /solr/clusterprops.json
   [junit4]   2>        9       /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        9       /solr/clusterstate.json
   [junit4]   2>        5       /solr/collections/implicitcoll/state.json
   [junit4]   2>        4       
/solr/collections/testCreateShardRepFactor/state.json
   [junit4]   2>        4       /solr/collections/routeFieldColl/state.json
   [junit4]   2>        4       /solr/collections/withShardField/state.json
   [junit4]   2>        2       
/solr/overseer_elect/election/97994322237849612-127.0.0.1:64143_solr-n_0000000001
   [junit4]   2>        2       
/solr/collections/withShardField/leader_elect/a/election/97994322237849612-core_node6-n_0000000000
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        27      /solr/collections
   [junit4]   2>        9       /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> 2213413 INFO  
(SUITE-CustomCollectionTest-seed#[A011E773833B4BD1]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.CustomCollectionTest_A011E773833B4BD1-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{shard_s=PostingsFormat(name=Memory doPackFST= true), 
_version_=PostingsFormat(name=Memory doPackFST= true), 
id=PostingsFormat(name=Asserting), _route_=FSTOrd50}, docValues:{}, 
maxPointsInLeafNode=326, maxMBSortInHeap=7.01504353339864, 
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ru, 
timezone=PST8PDT
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=217776088,total=470417408
   [junit4]   2> NOTE: All tests run in this JVM: [TestRangeQuery, 
ReplicaListTransformerTest, TestClassicSimilarityFactory, 
ChaosMonkeySafeLeaderTest, ScriptEngineTest, UUIDFieldTest, 
ShardRoutingCustomTest, TestEmbeddedSolrServerSchemaAPI, 
MissingSegmentRecoveryTest, SolrGraphiteReporterTest, TestJmxMonitoredMap, 
TestQueryTypes, CoreAdminHandlerTest, TestIntervalFaceting, 
TestCloudPseudoReturnFields, TestSystemIdResolver, TestCollectionAPIs, 
ConnectionReuseTest, TestInfoStreamLogging, RestartWhileUpdatingTest, 
TestSolrCoreProperties, BinaryUpdateRequestHandlerTest, TestFieldTypeResource, 
TestRandomFlRTGCloud, TestBulkSchemaConcurrent, TestFoldingMultitermQuery, 
TestRandomFaceting, IndexSchemaTest, TestSolrXml, TestMiniSolrCloudClusterSSL, 
TestSolrConfigHandlerCloud, ShowFileRequestHandlerTest, 
TestConfigSetsAPIZkFailure, TestFreeTextSuggestions, TestConfigOverlay, 
CollectionsAPIDistributedZkTest, TestReload, TestFastWriter, 
ExternalFileFieldSortTest, TestSolr4Spatial, HLLUtilTest, FacetPivotSmallTest, 
DistributedFacetPivotLargeTest, ReplaceNodeTest, TestConfig, 
BasicDistributedZk2Test, TestHdfsCloudBackupRestore, SolrIndexMetricsTest, 
MetricUtilsTest, CopyFieldTest, UpdateLogTest, TestDynamicLoading, 
TestManagedSchema, TestSolrCoreSnapshots, TestRuleBasedAuthorizationPlugin, 
BitVectorTest, SolrMetricManagerTest, DistributedFacetPivotSmallAdvancedTest, 
SolrInfoMBeanTest, CoreSorterTest, CollectionsAPISolrJTest, 
SolrIndexSplitterTest, FullSolrCloudDistribCmdsTest, TestQuerySenderListener, 
TestSuggestSpellingConverter, TestCrossCoreJoin, 
TestComplexPhraseQParserPlugin, CurrencyFieldOpenExchangeTest, TestBlobHandler, 
BlockJoinFacetRandomTest, TestAuthorizationFramework, DistribCursorPagingTest, 
SecurityConfHandlerTest, TestRecovery, TestTrie, TestQueryWrapperFilter, 
AnalyticsMergeStrategyTest, TestFieldCache, TestNumericTerms32, 
TestNestedDocsSort, MBeansHandlerTest, CollectionTooManyReplicasTest, 
TermVectorComponentDistributedTest, TestClusterStateMutator, 
TestCorePropertiesReload, TestRebalanceLeaders, TestZkChroot, 
TestPHPSerializedResponseWriter, TestMissingGroups, TestUtils, 
MultiThreadedOCPTest, HdfsBasicDistributedZkTest, TestSchemaManager, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, WordBreakSolrSpellCheckerTest, 
BasicZkTest, EchoParamsTest, BooleanFieldTest, FileBasedSpellCheckerTest, 
GraphQueryTest, DistributedIntervalFacetingTest, 
CoreMergeIndexesAdminHandlerTest, MetricsHandlerTest, RequestHandlersTest, 
TestMacros, SolrMetricsIntegrationTest, SmileWriterTest, 
LeaderInitiatedRecoveryOnShardRestartTest, 
ClassificationUpdateProcessorIntegrationTest, TestFieldResource, TestTrieFacet, 
ExitableDirectoryReaderTest, TestBlendedInfixSuggestions, DateRangeFieldTest, 
DateMathParserTest, SolrCLIZkUtilsTest, TestFieldCacheSanityChecker, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestAtomicUpdateErrorCases, 
CreateCollectionCleanupTest, LeaderElectionContextKeyTest, 
TestSolrQueryParserDefaultOperatorResource, TestCollationFieldDocValues, 
TestDefaultStatsCache, TestSolrConfigHandler, RAMDirectoryFactoryTest, 
TlogReplayBufferedWhileIndexingTest, TestConfigSetsAPI, 
TestSlowCompositeReaderWrapper, TestSolrQueryParser, 
AsyncCallRequestStatusResponseTest, AtomicUpdatesTest, TestSolrCLIRunExample, 
SpellCheckComponentTest, TestTolerantUpdateProcessorCloud, 
PreAnalyzedFieldManagedSchemaCloudTest, TestCloudDeleteByQuery, 
HighlighterMaxOffsetTest, TestSolrCloudWithHadoopAuthPlugin, 
CleanupOldIndexTest, TestDFRSimilarityFactory, CoreAdminCreateDiscoverTest, 
TestDistributedGrouping, TestCoreDiscovery, TestShortCircuitedRequests, 
TestPerFieldSimilarityWithDefaultOverride, FieldAnalysisRequestHandlerTest, 
OverseerTaskQueueTest, RuleEngineTest, TestSolrDeletionPolicy2, 
TestUninvertingReader, HdfsRecoveryZkTest, SSLMigrationTest, 
TestCollapseQParserPlugin, StatsComponentTest, CircularListTest, 
TestXmlQParserPlugin, RegexBoostProcessorTest, TestManagedSynonymFilterFactory, 
DistributedTermsComponentTest, DataDrivenBlockJoinTest, JvmMetricsTest, 
TestGeoJSONResponseWriter, SliceStateTest, TestSolrCoreParser, 
TestFieldCacheWithThreads, SpellCheckCollatorWithCollapseTest, 
TestFuzzyAnalyzedSuggestions, TestIndexingPerformance, 
TestSchemaVersionResource, TestLMJelinekMercerSimilarityFactory, TestNRTOpen, 
FieldMutatingUpdateProcessorTest, OutOfBoxZkACLAndCredentialsProvidersTest, 
DistanceUnitsTest, UnloadDistributedZkTest, SyncSliceTest, 
OpenCloseCoreStressTest, LeaderElectionIntegrationTest, LeaderElectionTest, 
ZkSolrClientTest, TestRandomDVFaceting, AutoCommitTest, TestHashPartitioner, 
TestRealTimeGet, TestStressVersions, SolrCoreTest, BadIndexSchemaTest, 
ConvertedLegacyTest, TestFiltering, TestLazyCores, HighlighterTest, 
SimplePostToolTest, SignatureUpdateProcessorFactoryTest, 
SpellCheckCollatorTest, SuggesterTSTTest, TestStressLucene, SuggesterWFSTTest, 
NoCacheHeaderTest, TestPseudoReturnFields, QueryEqualityTest, 
SortByFunctionTest, XsltUpdateRequestHandlerTest, LukeRequestHandlerTest, 
TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TestOmitPositions, 
TestValueSourceCache, TermVectorComponentTest, LoggingHandlerTest, 
ReturnFieldsTest, JSONWriterTest, QueryParsingTest, PingRequestHandlerTest, 
TestBinaryResponseWriter, HighlighterConfigTest, UpdateParamsTest, 
TestSolrIndexConfig, TestQuerySenderNoQuery, ResponseLogComponentTest, 
SolrIndexConfigTest, MultiTermTest, SampleTest, TestDocSet, NumericFieldsTest, 
MinimalSchemaTest, NotRequiredUniqueKeyTest, TestPhraseSuggestions, 
TestXIncludeConfig, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, 
TestIBSimilarityFactory, ResourceLoaderTest, OpenExchangeRatesOrgProviderTest, 
PluginInfoTest, SpellingQueryConverterTest, ClusterStateTest, FileUtilsTest, 
TestRTGBase, TestCursorMarkWithoutUniqueKey, TestSimpleTrackingShardHandler, 
TestPathTrie, AliasIntegrationTest, CdcrReplicationDistributedZkTest, 
CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, 
CloudExitableDirectoryReaderTest, CollectionReloadTest, 
CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, 
ConfigSetsAPITest, ConnectionManagerTest, CustomCollectionTest]
   [junit4] Completed [495/712 (2!)] on J1 in 55.93s, 4 tests, 1 error <<< 
FAILURES!

[...truncated 8216 lines...]
-validate-source-patterns:
[source-patterns] tabs instead spaces: solr/solr-ref-guide/src/css/ref-guide.css
[source-patterns] tabs instead spaces: 
solr/solr-ref-guide/src/js/ref-guide-toc.js

BUILD FAILED
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:775: The following 
error occurred while executing this line:
/Users/jenkins/workspace/Lucene-Solr-6.x-MacOSX/build.xml:130: Found 2 
violations in source files (tabs instead spaces).

Total time: 81 minutes 46 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to