lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 731 - Still Failing
Date Mon, 06 Jul 2015 07:00:30 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/731/

2 tests failed.
FAILED:  org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test

Error Message:
Error from server at http://127.0.0.1:51444/pjz/vr: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51444/pjz/vr: Error CREATEing SolrCore 'halfcollection_shard1_replica1': Unable to create core [halfcollection_shard1_replica1] Caused by: Could not get shard id for core: halfcollection_shard1_replica1
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:301)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:418)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:168)
	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:483)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
	at java.lang.Thread.run(Thread.java:745)


FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:
Captured an uncaught exception in thread: Thread[id=10620, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=10620, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest]
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46117/_hjo: Could not find collection : awholynewstresscollection_collection1_0
	at __randomizedtesting.SeedInfo.seed([469B532E9E5F7181]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376)
	at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856)
	at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799)
	at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220)
	at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)




Build Log:
[...truncated 10550 lines...]
   [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/init-core-data-001
   [junit4]   2> 949067 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[469B532E9E5F7181]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 949067 INFO  (SUITE-CollectionsAPIDistributedZkTest-seed#[469B532E9E5F7181]-worker) [    ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /pjz/vr
   [junit4]   2> 949071 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 949074 INFO  (Thread-7237) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 949074 INFO  (Thread-7237) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 949174 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkTestServer start zk server on port:53636
   [junit4]   2> 949174 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 949176 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949185 INFO  (zkCallback-647-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@509a9c5e name:ZooKeeperConnection Watcher:127.0.0.1:53636 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949186 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949186 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 949186 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 949189 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 949189 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949200 INFO  (zkCallback-648-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5a430f86 name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949200 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949201 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 949201 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 949205 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 949206 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 949207 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 949208 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
   [junit4]   2> 949208 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 949210 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
   [junit4]   2> 949210 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 949212 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 949212 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 949213 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
   [junit4]   2> 949213 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 949215 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
   [junit4]   2> 949215 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 949216 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
   [junit4]   2> 949217 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 949218 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
   [junit4]   2> 949218 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 949219 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 949220 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 949222 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 949223 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 949224 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
   [junit4]   2> 949224 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 949230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
   [junit4]   2> 949230 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 949232 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 949242 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949253 INFO  (zkCallback-649-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@279be0cb name:ZooKeeperConnection Watcher:127.0.0.1:53636 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 949254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 949257 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 949264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949264 INFO  (zkCallback-650-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@24833ec5 name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949264 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 949265 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml
   [junit4]   2> 949265 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 949267 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml
   [junit4]   2> 949267 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 949269 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml
   [junit4]   2> 949269 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 949270 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 949270 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 949271 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt
   [junit4]   2> 949272 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 949273 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt
   [junit4]   2> 949273 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 949274 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml
   [junit4]   2> 949274 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 949275 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 949276 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 949277 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 949277 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 949278 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt
   [junit4]   2> 949278 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 949280 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt
   [junit4]   2> 949280 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 949281 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml
   [junit4]   2> 949281 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 949634 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1
   [junit4]   2> 949636 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 949639 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@623534ee{/pjz/vr,null,AVAILABLE}
   [junit4]   2> 949640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.ServerConnector Started ServerConnector@12afb48f{HTTP/1.1}{127.0.0.1:46450}
   [junit4]   2> 949640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.Server Started @951147ms
   [junit4]   2> 949640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/tempDir-001/control/data, hostContext=/pjz/vr, hostPort=46450, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores}
   [junit4]   2> 949640 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 949641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/'
   [junit4]   2> 949656 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/solr.xml
   [junit4]   2> 949661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores
   [junit4]   2> 949661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer New CoreContainer 952693075
   [junit4]   2> 949661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/]
   [junit4]   2> 949661 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/lib
   [junit4]   2> 949661 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/lib).
   [junit4]   2> 949668 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 949669 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53636/solr
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 949670 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949672 INFO  (zkCallback-652-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69687627 name:ZooKeeperConnection Watcher:127.0.0.1:53636 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949672 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949672 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 949677 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 949678 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e80cc7b name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 949678 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 949679 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 949680 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
   [junit4]   2> 949682 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
   [junit4]   2> 949683 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
   [junit4]   2> 949685 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
   [junit4]   2> 949687 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 949688 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 949689 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 949690 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46450_pjz%2Fvr
   [junit4]   2> 949690 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46450_pjz%2Fvr
   [junit4]   2> 949691 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 949692 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
   [junit4]   2> 949693 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 949693 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000
   [junit4]   2> 949694 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46450_pjz%2Fvr
   [junit4]   2> 949694 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 949696 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer Overseer (id=94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) starting
   [junit4]   2> 949697 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 949707 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 949710 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 949714 INFO  (OverseerCollectionProcessor-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations
   [junit4]   2> 949723 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 949728 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 949736 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 949736 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores
   [junit4]   2> 949737 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
   [junit4]   2> 949738 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/
   [junit4]   2> 949738 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 949747 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1
   [junit4]   2> 949748 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
   [junit4]   2> 949748 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController publishing state=down
   [junit4]   2> 949748 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 949749 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 949749 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController look for our core node name
   [junit4]   2> 949750 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46450/pjz/vr",
   [junit4]   2>   "node_name":"127.0.0.1:46450_pjz%2Fvr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 949750 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46450/pjz/vr",
   [junit4]   2>   "node_name":"127.0.0.1:46450_pjz%2Fvr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 949751 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
   [junit4]   2> 949751 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
   [junit4]   2> 949752 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 949753 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 950750 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 950750 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 950751 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 950751 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 950752 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 950752 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/'
   [junit4]   2> 950766 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 
   [junit4]   2> 950770 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 950774 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 950782 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 950783 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 950787 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 950890 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 950898 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 950899 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 950907 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 950909 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 950911 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 950912 WARN  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 950912 WARN  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 950912 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 950913 WARN  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 950913 WARN  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 950913 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection   x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
   [junit4]   2> 950913 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 950914 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/], dataDir=[null]
   [junit4]   2> 950914 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@210afb3f
   [junit4]   2> 950914 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data
   [junit4]   2> 950915 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data/index/
   [junit4]   2> 950915 WARN  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
   [junit4]   2> 950915 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data/index
   [junit4]   2> 950915 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.1865234375, noCFSRatio=0.25886972753408866]
   [junit4]   2> 950916 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@2eb184f3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a8723),segFN=segments_1,generation=1}
   [junit4]   2> 950916 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 950916 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data/
   [junit4]   2> 950917 WARN  (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
   [junit4]   2> 950920 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 950920 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 950920 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 950921 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 950923 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 950929 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 950935 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 950936 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 950940 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 950940 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 950941 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 950941 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 950944 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 950944 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 950944 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=39, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.3408203125, noCFSRatio=1.0]
   [junit4]   2> 950945 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@2eb184f3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@56a8723),segFN=segments_1,generation=1}
   [junit4]   2> 950945 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 950945 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7e3027f[collection1] main
   [junit4]   2> 950945 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
   [junit4]   2> 950947 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
   [junit4]   2> 950947 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
   [junit4]   2> 950947 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
   [junit4]   2> 950947 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 950948 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 950948 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
   [junit4]   2> 950948 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
   [junit4]   2> 950948 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 950948 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 950949 INFO  (searcherExecutor-3043-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7e3027f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 950949 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
   [junit4]   2> 950949 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
   [junit4]   2> 950950 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
   [junit4]   2> 950950 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1505925852806774784
   [junit4]   2> 950952 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 1505925852806774784
   [junit4]   2> 950953 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 950955 INFO  (coreLoadExecutor-3042-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 950958 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:46450/pjz/vr collection:control_collection shard:shard1
   [junit4]   2> 950962 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
   [junit4]   2> 950965 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94120365677608965-core_node1-n_0000000000
   [junit4]   2> 950966 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 950967 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46450/pjz/vr/collection1/
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46450/pjz/vr/collection1/ has no replicas
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46450/pjz/vr/collection1/ shard1
   [junit4]   2> 950968 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 950973 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 950974 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 950976 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:46450/pjz/vr",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 950977 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 951078 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 951080 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 951122 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46450/pjz/vr/collection1/ and leader is http://127.0.0.1:46450/pjz/vr/collection1/
   [junit4]   2> 951122 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46450/pjz/vr
   [junit4]   2> 951122 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 951123 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
   [junit4]   2> 951123 INFO  (coreZkRegister-3036-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46450_pjz%2Fvr c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
   [junit4]   2> 951130 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 951131 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46450/pjz/vr",
   [junit4]   2>   "node_name":"127.0.0.1:46450_pjz%2Fvr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"} current state version: 2
   [junit4]   2> 951131 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "roles":null,
   [junit4]   2>   "base_url":"http://127.0.0.1:46450/pjz/vr",
   [junit4]   2>   "node_name":"127.0.0.1:46450_pjz%2Fvr",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 951234 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 951235 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 951249 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 951251 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 951251 INFO  (zkCallback-655-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d8295b6 name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 951251 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 951252 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 951252 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 951254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
   [junit4]   2> 951254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 951254 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 951260 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 951261 INFO  (zkCallback-656-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@386298f5 name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 951261 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 951261 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 951262 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected
   [junit4]   2> 951263 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 3
   [junit4]   2> 951263 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
   [junit4]   2> 951366 INFO  (OverseerStateUpdate-94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json
   [junit4]   2> 951367 INFO  (zkCallback-655-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 951367 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
   [junit4]   2> 951375 INFO  (zkCallback-655-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 951375 INFO  (zkCallback-653-thread-1-processing-n:127.0.0.1:46450_pjz%2Fvr) [n:127.0.0.1:46450_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 951575 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/cores/collection1
   [junit4]   2> 951576 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001
   [junit4]   2> 951577 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 951579 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19319654{/pjz/vr,null,AVAILABLE}
   [junit4]   2> 951579 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.ServerConnector Started ServerConnector@5a7d2c62{HTTP/1.1}{127.0.0.1:51444}
   [junit4]   2> 951579 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.e.j.s.Server Started @953086ms
   [junit4]   2> 951579 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/pjz/vr, hostPort=51444, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/cores}
   [junit4]   2> 951580 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44
   [junit4]   2> 951580 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/'
   [junit4]   2> 951596 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/solr.xml
   [junit4]   2> 951601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/cores
   [junit4]   2> 951601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer New CoreContainer 281872381
   [junit4]   2> 951601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/]
   [junit4]   2> 951601 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/lib
   [junit4]   2> 951601 WARN  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_469B532E9E5F7181-001/shard-1-001/lib).
   [junit4]   2> 951608 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 951610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 951610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 951610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 951610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53636/solr
   [junit4]   2> 951610 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 951611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 951611 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 951613 INFO  (zkCallback-658-thread-1) [    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f4d2832 name:ZooKeeperConnection Watcher:127.0.0.1:53636 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 951613 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 951614 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [    ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 951618 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 951621 INFO  (zkCallback-659-thread-1-processing-n:127.0.0.1:51444_pjz%2Fvr) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@fb579da name:ZooKeeperConnection Watcher:127.0.0.1:53636/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 951622 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 951633 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 952638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51444_pjz%2Fvr
   [junit4]   2> 952638 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51444_pjz%2Fvr
   [junit4]   2> 952641 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 952642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94120365677608969-127.0.0.1:51444_pjz%2Fvr-n_0000000001
   [junit4]   2> 952642 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94120365677608965-127.0.0.1:46450_pjz%2Fvr-n_0000000000 to know if I could be the leader
   [junit4]   2> 952643 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 952643 INFO  (TEST-CollectionsAPIDistributedZkTest.test-seed#[469B532E9E5F7181]) [n:127.0.0.1:51444_pjz%2Fvr    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 952644 INFO  (TEST-Col

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

doop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
   [junit4]   2> 	at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
   [junit4]   2> 	at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
   [junit4]   2> 	at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
   [junit4]   2> 	at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
   [junit4]   2> 	at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
   [junit4]   2> 	at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197)
   [junit4]   2> 	at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:483)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
   [junit4]   2> 	at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
   [junit4]   2> 	... 53 more
   [junit4]   2> 2559990 INFO  (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[469B532E9E5F7181]-worker) [n:127.0.0.1:39499__hjo c:addReplicaColl s:shard2 r:core_node1 x:addReplicaColl_shard2_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jul 06, 2015 2:33:39 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=PostingsFormat(name=Memory doPackFST= false), range_facet_l_dv=FST50, _version_=PostingsFormat(name=Memory doPackFST= false), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_t=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=Memory doPackFST= false), a_i1=PostingsFormat(name=Memory doPackFST= false), id=FST50, range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), text=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=lv_LV, timezone=Asia/Omsk
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=110941984,total=526385152
   [junit4]   2> NOTE: All tests run in this JVM: [TestSearcherReuse, AtomicUpdatesTest, HdfsLockFactoryTest, PluginInfoTest, TestFastOutputStream, TestRawResponseWriter, ChangedSchemaMergeTest, UnloadDistributedZkTest, DistributedVersionInfoTest, HardAutoCommitTest, TestXIncludeConfig, TestSmileRequest, AsyncMigrateRouteKeyTest, RollingRestartTest, TestSolrCloudWithKerberosAlt, OverseerCollectionProcessorTest, TestReloadAndDeleteDocs, TestSurroundQueryParser, OverriddenZkACLAndCredentialsProvidersTest, NumericFieldsTest, TestReplicationHandlerBackup, DistribJoinFromCollectionTest, TestTrie, TestCopyFieldCollectionResource, TestGroupingSearch, BasicDistributedZk2Test, TestObjectReleaseTracker, CloudMLTQParserTest, DistanceUnitsTest, TestSolrQueryParserDefaultOperatorResource, TestExtendedDismaxParser, MultiThreadedOCPTest, SearchHandlerTest, DistributedDebugComponentTest, RegexBoostProcessorTest, XsltUpdateRequestHandlerTest, TestCSVResponseWriter, TestMiniSolrCloudClusterSSL, TestSolr4Spatial2, DistributedQueryComponentOptimizationTest, TestFieldTypeResource, OutputWriterTest, DeleteReplicaTest, TestSchemaSimilarityResource, TestStressReorder, VersionInfoTest, DocValuesTest, SharedFSAutoReplicaFailoverTest, CoreMergeIndexesAdminHandlerTest, TestLuceneMatchVersion, TestSystemIdResolver, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [215/514] on J2 in 758.82s, 1 test, 1 error <<< FAILURES!

[...truncated 941 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 514 suites, 2022 tests, 2 errors, 20 ignored (10 assumptions)

Total time: 198 minutes 36 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message