Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/2/

1 tests failed.
FAILED:  org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test

Error Message:


Stack Trace:
java.lang.AssertionError
        at 
__randomizedtesting.SeedInfo.seed([E66205D332A8EEF8:6E363A099C548300]:0)
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertNull(Assert.java:551)
        at org.junit.Assert.assertNull(Assert.java:562)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testNoConfigSetExist(CollectionsAPIDistributedZkTest.java:518)
        at 
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:165)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        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:963)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
        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)




Build Log:
[...truncated 10617 lines...]
   [junit4] Suite: 
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/init-core-data-001
   [junit4]   2> 926549 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 926549 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/rx
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 926599 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried 
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 926615 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 926618 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 926634 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs
 to ./temp/Jetty_localhost_47244_hdfs____9jzmgp/webapp
   [junit4]   2> 926782 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 927017 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47244
   [junit4]   2> 927158 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 927160 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 927174 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_57642_datanode____btwzzt/webapp
   [junit4]   2> 927298 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 927557 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57642
   [junit4]   2> 927602 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 927604 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 927622 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Extract 
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode
 to ./temp/Jetty_localhost_46012_datanode____fg0q9i/webapp
   [junit4]   2> 927667 INFO  (IPC Server handler 3 on 40518) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-623ff233-b980-481f-b99e-39a3286fa9f9 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=3fc8e770-74c6-4624-a4ca-c18562b58fd8, infoPort=57642, 
ipcPort=51352, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 1 msecs
   [junit4]   2> 927667 INFO  (IPC Server handler 3 on 40518) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a node DatanodeRegistration(127.0.0.1, 
datanodeUuid=3fc8e770-74c6-4624-a4ca-c18562b58fd8, infoPort=57642, 
ipcPort=51352, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 927757 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 927973 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46012
   [junit4]   2> 928053 INFO  (IPC Server handler 8 on 40518) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-63f26b3f-1172-436e-9b66-84db40aa6f22 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=b2c1bc6b-aa95-46fb-ad1a-330a6d1947b1, infoPort=46012, 
ipcPort=42744, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 928054 INFO  (IPC Server handler 8 on 40518) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-0ec8f07f-67d7-410c-a069-2ef6606c6cfb node DatanodeRegistration(127.0.0.1, 
datanodeUuid=b2c1bc6b-aa95-46fb-ad1a-330a6d1947b1, infoPort=46012, 
ipcPort=42744, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 928136 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 928136 INFO  (Thread-7346) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 928136 INFO  (Thread-7346) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 928236 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:40111
   [junit4]   2> 928237 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928237 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928240 INFO  (zkCallback-600-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1fb056f3 
name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928240 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928241 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928241 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 928243 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928244 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928245 INFO  (zkCallback-601-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@76f66e02 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928245 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928245 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928245 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 928247 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 928249 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 928250 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 928252 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf1/solrconfig.xml
   [junit4]   2> 928252 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 928255 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf1/schema.xml
   [junit4]   2> 928255 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 928257 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 928257 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 928261 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf1/stopwords.txt
   [junit4]   2> 928261 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 928265 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf1/protwords.txt
   [junit4]   2> 928265 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 928267 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf1/currency.xml
   [junit4]   2> 928267 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 928269 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf1/enumsConfig.xml
   [junit4]   2> 928269 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 928274 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf1/open-exchange-rates.json
   [junit4]   2> 928274 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 928276 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 928276 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 928278 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf1/old_synonyms.txt
   [junit4]   2> 928278 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 928280 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf1/synonyms.txt
   [junit4]   2> 928280 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 928282 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928282 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928284 INFO  (zkCallback-602-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@1014e07b 
name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928284 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928284 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928284 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 928286 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928288 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928288 INFO  (zkCallback-603-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4b3f64a9 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928289 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928289 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928289 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
 to /configs/conf2/solrconfig.xml
   [junit4]   2> 928290 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 928292 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml
 to /configs/conf2/schema.xml
   [junit4]   2> 928292 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 928294 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
 to /configs/conf2/enumsConfig.xml
   [junit4]   2> 928294 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 928296 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
 to /configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 928297 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 928298 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
 to /configs/conf2/stopwords.txt
   [junit4]   2> 928299 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 928301 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt
 to /configs/conf2/protwords.txt
   [junit4]   2> 928301 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 928304 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml
 to /configs/conf2/currency.xml
   [junit4]   2> 928304 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 928306 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
 to /configs/conf2/open-exchange-rates.json
   [junit4]   2> 928306 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 928308 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
 to /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 928308 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 928309 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
 to /configs/conf2/old_synonyms.txt
   [junit4]   2> 928310 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 928311 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
 to /configs/conf2/synonyms.txt
   [junit4]   2> 928312 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 928314 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractZkTestCase put 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml
 to /configs/conf2/elevate.xml
   [junit4]   2> 928314 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 928405 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1
   [junit4]   2> 928407 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 928409 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7baf1b69{/_/rx,null,AVAILABLE}
   [junit4]   2> 928409 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@26197cfc{HTTP/1.1}{127.0.0.1:44944}
   [junit4]   2> 928409 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.Server Started @931662ms
   [junit4]   2> 928409 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/rx, 
solr.data.dir=hdfs://localhost:40518/hdfs__localhost_40518__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001_tempDir-002_control_data,
 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores,
 hostPort=44944}
   [junit4]   2> 928410 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 928410 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/'
   [junit4]   2> 928426 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928427 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928429 INFO  (zkCallback-604-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@6a0d45e4 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928429 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928430 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928431 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 928431 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/solr.xml
   [junit4]   2> 928446 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores
   [junit4]   2> 928446 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 743402390
   [junit4]   2> 928446 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/]
   [junit4]   2> 928446 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/lib
   [junit4]   2> 928446 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/lib).
   [junit4]   2> 928454 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
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> 928463 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 928463 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 928463 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 928463 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40111/solr
   [junit4]   2> 928464 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 928464 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 928464 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 928466 INFO  (zkCallback-606-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2139e0e4 
name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928467 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 928468 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 928469 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 928471 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@62f7cc64 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 928471 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 928472 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 928474 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 928477 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 928479 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 928482 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 928485 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 928487 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 928488 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/clusterstate.json
   [junit4]   2> 928489 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 928491 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:44944__%2Frx
   [junit4]   2> 928491 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:44944__%2Frx
   [junit4]   2> 928493 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 928494 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 928496 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 928496 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.LeaderElector Joined leadership election 
with path: 
/overseer_elect/election/95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000
   [junit4]   2> 928497 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:44944__%2Frx
   [junit4]   2> 928497 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/leader
   [junit4]   2> 928499 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer Overseer 
(id=95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) starting
   [junit4]   2> 928503 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/queue-work
   [junit4]   2> 928509 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.OverseerAutoReplicaFailoverThread 
Starting OverseerAutoReplicaFailoverThread 
autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 928510 INFO  
(OverseerCollectionProcessor-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000)
 [n:127.0.0.1:44944__%2Frx    ] o.a.s.c.OverseerCollectionProcessor Process 
current queue of collection creations
   [junit4]   2> 928510 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer Starting to work on the main 
queue
   [junit4]   2> 928510 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 928531 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.CoreContainer Security conf doesn't 
exist. Skipping setup for authorization module.
   [junit4]   2> 928532 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.CoreContainer No authentication plugin 
used.
   [junit4]   2> 928533 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.CoresLocator Looking for core 
definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores
   [junit4]   2> 928533 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{coreNodeName=, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1,
 config=solrconfig.xml, 
absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/,
 dataDir=data/, loadOnStartup=true, schema=schema.xml, transient=false, 
collection=control_collection, shard=, configSetProperties=configsetprops.json, 
name=collection1}
   [junit4]   2> 928534 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.CoresLocator Found core collection1 in 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/
   [junit4]   2> 928534 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 928535 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController publishing state=down
   [junit4]   2> 928535 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.s.SolrDispatchFilter 
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 928535 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.s.SolrDispatchFilter 
SolrDispatchFilter.init() done
   [junit4]   2> 928535 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController numShards not found on descriptor - reading it from system 
property
   [junit4]   2> 928536 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController look for our core node name
   [junit4]   2> 928536 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.DistributedQueue NodeChildrenChanged 
fired on path /overseer/queue state SyncConnected
   [junit4]   2> 928537 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "shard":null,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:44944/_/rx";,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:44944__%2Frx",
   [junit4]   2>   "roles":null} current state version: 0
   [junit4]   2> 928537 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.o.ReplicaMutator Update state 
numShards=1 message={
   [junit4]   2>   "shard":null,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "base_url":"http://127.0.0.1:44944/_/rx";,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:44944__%2Frx",
   [junit4]   2>   "roles":null}
   [junit4]   2> 928538 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.o.ClusterStateMutator building a new 
cName: control_collection
   [junit4]   2> 928538 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.o.ReplicaMutator Assigning new node to 
shard shard=shard1
   [junit4]   2> 928539 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] 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> 928541 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ZkStateReader Updated cluster state 
version to 1
   [junit4]   2> 929536 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
   [junit4]   2> 929537 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController Check for collection zkNode:control_collection
   [junit4]   2> 929537 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ZkController Collection zkNode exists
   [junit4]   2> 929537 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 929538 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 929538 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/'
   [junit4]   2> 929553 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] o.a.s.c.Config 
loaded config solrconfig.xml with version 0 
   [junit4]   2> 929558 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 929570 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 929618 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] o.a.s.c.Config 
Loaded SolrConfig: solrconfig.xml
   [junit4]   2> 929620 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 929627 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 929865 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 929873 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 929875 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 929884 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 929887 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 929890 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 929891 WARN  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 929891 WARN  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 929891 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 929892 WARN  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 929892 WARN  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 929893 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/configs/conf1', 
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2
   [junit4]   2> 929893 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection   x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
collection control_collection
   [junit4]   2> 929893 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
   [junit4]   2> 929894 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.HdfsDirectoryFactory 
solr.hdfs.home=hdfs://localhost:40518/solr_hdfs_home
   [junit4]   2> 929894 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication 
disabled
   [junit4]   2> 929894 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/],
 dataDir=[null]
   [junit4]   2> 929894 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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@458b797d
   [junit4]   2> 929894 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 929909 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data for 
locality metrics.
   [junit4]   2> 929909 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 929910 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index/
   [junit4]   2> 929920 WARN  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'hdfs:/localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index' 
doesn't exist. Creating new index...
   [junit4]   2> 929920 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 929933 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index 
for locality metrics.
   [junit4]   2> 929933 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 929934 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=38, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=3.25, 
floorSegmentMB=1.544921875, forceMergeDeletesPctAllowed=25.903679207795463, 
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6018182887718883
   [junit4]   2> 929950 INFO  (IPC Server handler 7 on 40518) [    ] 
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55552 is 
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, 
replicas=[ReplicaUnderConstruction[[DISK]DS-63f26b3f-1172-436e-9b66-84db40aa6f22:NORMAL:127.0.0.1:47967|RBW],
 
ReplicaUnderConstruction[[DISK]DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a:NORMAL:127.0.0.1:55552|FINALIZED]]}
 size 0
   [junit4]   2> 929952 INFO  (IPC Server handler 8 on 40518) [    ] 
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:47967 is 
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, 
replicas=[ReplicaUnderConstruction[[DISK]DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a:NORMAL:127.0.0.1:55552|FINALIZED],
 
ReplicaUnderConstruction[[DISK]DS-0ec8f07f-67d7-410c-a069-2ef6606c6cfb:NORMAL:127.0.0.1:47967|FINALIZED]]}
 size 0
   [junit4]   2> 929955 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=NRTCachingDirectory(HdfsDirectory@b6de4b64 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31d6fbbb; 
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 929955 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 929957 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44944__%2Frx 
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 
hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/
   [junit4]   2> 929962 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "nodistrib"
   [junit4]   2> 929962 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "dedupe"
   [junit4]   2> 929962 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 929962 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating 
updateRequestProcessorChain "stored_sig"
   [junit4]   2> 929962 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 929963 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 929963 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 929963 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 929963 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 929966 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 929968 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 929969 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 929971 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 929980 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/update,/admin/logging,/admin/ping,/admin/plugins,/schema,/admin/segments,/admin/properties,/config,/admin/mbeans,/admin/file,/get,/update/json,/update/csv,/admin/system,/replication,/admin/luke,standard,/update/json/docs,/admin/threads
   [junit4]   2> 929982 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 929982 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.HdfsUpdateLog
   [junit4]   2> 929982 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
tlogDfsReplication=2
   [junit4]   2> 929994 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 929994 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 929996 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=87.1708984375, 
floorSegmentMB=0.7177734375, forceMergeDeletesPctAllowed=25.426875340826996, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 930002 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=NRTCachingDirectory(HdfsDirectory@b6de4b64 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31d6fbbb; 
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 930002 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 930002 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7a7b444d[collection1] 
main
   [junit4]   2> 930002 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930003 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930004 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930004 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO 
with znodeBase: /configs/conf1
   [junit4]   2> 930004 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 930004 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930005 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930005 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930005 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 930005 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 930006 INFO  
(searcherExecutor-2043-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@7a7b444d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 930008 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930008 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 
256 version buckets from index
   [junit4]   2> 930008 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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> 930008 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx 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 1522306876077768704
   [junit4]   2> 930008 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.u.UpdateLog Took 0 ms to seed version buckets with highest 
version 1522306876077768704
   [junit4]   2> 930009 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 930009 INFO  
(coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 930010 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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:44944/_/rx 
collection:control_collection shard:shard1
   [junit4]   2> 930011 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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> 930014 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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/95144179632308230-core_node1-n_0000000000
   [junit4]   2> 930015 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 930017 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.DistributedQueue NodeChildrenChanged 
fired on path /overseer/queue state SyncConnected
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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> 930018 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "operation":"leader",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "collection":"control_collection"} current state version: 1
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:44944/_/rx/collection1/
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:44944/_/rx/collection1/ has no replicas
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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:44944/_/rx/collection1/ shard1
   [junit4]   2> 930018 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 930023 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.DistributedQueue NodeChildrenChanged 
fired on path /overseer/queue state SyncConnected
   [junit4]   2> 930024 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] 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:44944/_/rx";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 1
   [junit4]   2> 930037 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 930038 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 930039 INFO  (zkCallback-609-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@251d4e5a 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 930039 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 930040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 930040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 930043 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 930127 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] 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> 930127 INFO  (zkCallback-609-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> 930128 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ZkStateReader Updated cluster state 
version to 2
   [junit4]   2> 930132 INFO  (zkCallback-609-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 930144 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores/collection1
   [junit4]   2> 930145 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001
   [junit4]   2> 930146 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 930148 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5102f4be{/_/rx,null,AVAILABLE}
   [junit4]   2> 930149 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@70b767b4{HTTP/1.1}{127.0.0.1:49570}
   [junit4]   2> 930149 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.e.j.s.Server Started @933402ms
   [junit4]   2> 930149 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=hdfs://localhost:40518/hdfs__localhost_40518__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001_tempDir-002_jetty1,
 solrconfig=solrconfig.xml, hostContext=/_/rx, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores,
 hostPort=49570}
   [junit4]   2> 930150 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 930150 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/'
   [junit4]   2> 930167 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 930168 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 930169 INFO  (zkCallback-610-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@becda9c name:ZooKeeperConnection 
Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 930169 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 930170 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 930171 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 930171 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/solr.xml
   [junit4]   2> 930174 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:44944/_/rx/collection1/ and leader is 
http://127.0.0.1:44944/_/rx/collection1/
   [junit4]   2> 930174 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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:44944/_/rx
   [junit4]   2> 930174 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 930174 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 930174 INFO  
(coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 
c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 
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> 930175 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.DistributedQueue NodeChildrenChanged 
fired on path /overseer/queue state SyncConnected
   [junit4]   2> 930176 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.Overseer processMessage: queueSize: 1, 
message = {
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "base_url":"http://127.0.0.1:44944/_/rx";,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:44944__%2Frx",
   [junit4]   2>   "roles":null} current state version: 2
   [junit4]   2> 930177 INFO  
(OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.o.ReplicaMutator Update state 
numShards=2 message={
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "state":"active",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "base_url":"http://127.0.0.1:44944/_/rx";,
   [junit4]   2>   "operation":"state",
   [junit4]   2>   "node_name":"127.0.0.1:44944__%2Frx",
   [junit4]   2>   "roles":null}
   [junit4]   2> 930187 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoresLocator Config-defined core root directory: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores
   [junit4]   2> 930188 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 964384298
   [junit4]   2> 930188 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/]
   [junit4]   2> 930188 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/lib
   [junit4]   2> 930188 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/lib).
   [junit4]   2> 930197 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
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> 930198 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 930199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 930199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 930199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40111/solr
   [junit4]   2> 930199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 930199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 930200 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 930201 INFO  (zkCallback-612-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@4ad52a35 
name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 930202 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 930202 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 930204 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:49570__%2Frx    ] o.a.s.c.c.ConnectionManager Waiting for client 
to connect to ZooKeeper
   [junit4]   2> 930205 INFO  
(zkCallback-613-thread-1-processing-n:127.0.0.1:49570__%2Frx) 
[n:127.0.0.1:49570__%2Frx    ] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@103ce784 
name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 930206 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:49570__%2Frx    ] o.a.s.c.c.ConnectionManager Client is connected 
to ZooKeeper
   [junit4]   2> 930210 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) 
[n:127.0.0.1:49570__%2Frx    ] o.a.s.c.c.ZkStateReader Updating cluster state 
from ZooKeeper... 
   [junit4]   2> 930280 INFO  (zkCallback-609-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> 930280 INFO  
(zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) 
[n:127.0.0.1:44944__%2Frx    ] o.a.s.c.c.ZkStateReader A cluster state change: 
WatchedEvent state:SyncConnected type

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

MetricsRegistry.java:387)
   [junit4]   2>        at 
org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79)
   [junit4]   2>        at 
org.apache.hadoop.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:57)
   [junit4]   2>        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2>        at java.lang.reflect.Method.invoke(Method.java:606)
   [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> 959641 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) 
[n:127.0.0.1:50949__%2Frx c:nodes_used_collection s:shard2 r:core_node1 
x:nodes_used_collection_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001
   [junit4]   2> Jan 03, 2016 1:36: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): {}, 
docValues:{}, sim=DefaultSimilarity, locale=es_US, timezone=Australia/Sydney
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=4,threads=2,free=149325592,total=406323200
   [junit4]   2> NOTE: All tests run in this JVM: 
[DirectUpdateHandlerOptimizeTest, HdfsRecoverLeaseTest, 
DirectSolrSpellCheckerTest, RecoveryZkTest, TermVectorComponentDistributedTest, 
CleanupOldIndexTest, TestPivotHelperCode, TestPerFieldSimilarity, SampleTest, 
TestManagedSchemaDynamicFieldResource, TestConfigSetProperties, 
ExitableDirectoryReaderTest, DistribJoinFromCollectionTest, 
TestClassNameShortening, ResponseLogComponentTest, TestAuthenticationFramework, 
TestNonDefinedSimilarityFactory, TestSchemaManager, HdfsRecoveryZkTest, 
TestDocBasedVersionConstraints, DistributedExpandComponentTest, 
TestSearcherReuse, DirectUpdateHandlerTest, QueryResultKeyTest, 
CoreAdminRequestStatusTest, TestUtils, TestBulkSchemaAPI, TestTolerantSearch, 
TestDefaultSimilarityFactory, TestRemoveLastDynamicCopyField, 
PathHierarchyTokenizerFactoryTest, TestCollationFieldDocValues, 
StatsComponentTest, TestBlendedInfixSuggestions, TestSolrCLIRunExample, 
TestRTimer, TestFastOutputStream, TestStressRecovery, RegexBoostProcessorTest, 
SortByFunctionTest, TestFieldCollectionResource, SpellCheckCollatorTest, 
TestSolrJ, TestRecovery, LeaderElectionTest, TestExtendedDismaxParser, 
QueryParsingTest, TestSchemaSimilarityResource, SolrTestCaseJ4Test, 
TestCloudInspectUtil, TestBM25SimilarityFactory, TestGroupingSearch, 
CloudExitableDirectoryReaderTest, StressHdfsTest, TestTrieFacet, 
TestStressReorder, RecoveryAfterSoftCommitTest, 
AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, TestBinaryResponseWriter, 
PrimUtilsTest, LoggingHandlerTest, SynonymTokenizerTest, 
TestSimpleTrackingShardHandler, DistributedQueryElevationComponentTest, 
TestZkChroot, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, 
BadIndexSchemaTest, CollectionReloadTest, SolrIndexSplitterTest, 
CoreMergeIndexesAdminHandlerTest, HdfsCollectionsAPIDistributedZkTest]
   [junit4] Completed [142/533] on J2 in 42.95s, 1 test, 1 failure <<< FAILURES!

[...truncated 1239 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: 
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003:
 There were test failures: 533 suites, 2103 tests, 1 failure, 26 ignored (14 
assumptions)

Total time: 175 minutes 23 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 - Any
Sending email for trigger: Failure - Any


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to