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

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

Error Message:
GC overhead limit exceeded

Stack Trace:
java.lang.OutOfMemoryError: GC overhead limit exceeded
        at 
__randomizedtesting.SeedInfo.seed([667D296926F4C3DA:EE2916B38808AE22]:0)
        at java.util.HashMap.entrySet0(HashMap.java:1073)
        at java.util.HashMap.entrySet(HashMap.java:1068)
        at java.util.AbstractMap.hashCode(AbstractMap.java:492)
        at java.util.HashMap.hash(HashMap.java:362)
        at java.util.HashMap.put(HashMap.java:492)
        at java.util.HashSet.add(HashSet.java:217)
        at 
org.apache.solr.cloud.CloudInspectUtil.showDiff(CloudInspectUtil.java:125)
        at 
org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:206)
        at 
org.apache.solr.cloud.CloudInspectUtil.compareResults(CloudInspectUtil.java:167)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.testIndexingBatchPerRequestWithHttpSolrClient(FullSolrCloudDistribCmdsTest.java:677)
        at 
org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test(FullSolrCloudDistribCmdsTest.java:153)
        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)


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

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

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




Build Log:
[...truncated 10622 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_667D296926F4C3DA-001/init-core-data-001
   [junit4]   2> 701312 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 701313 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   1> Formatting using clusterid: testClusterID
   [junit4]   2> 701361 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried 
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
   [junit4]   2> 701374 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 701378 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 701396 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_59906_hdfs____.wqvzaz/webapp
   [junit4]   2> 701523 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 701772 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:59906
   [junit4]   2> 701854 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 701856 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 701873 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_51543_datanode____wpfein/webapp
   [junit4]   2> 701997 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 702231 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51543
   [junit4]   2> 702308 WARN  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
   [junit4]   2> 702310 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log jetty-6.1.26
   [junit4]   2> 702329 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-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_58700_datanode____fraaj/webapp
   [junit4]   2> 702375 INFO  (IPC Server handler 3 on 40597) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-bbf7e801-f104-410b-ad19-802438a467bf node DatanodeRegistration(127.0.0.1, 
datanodeUuid=9328feef-42b3-4438-93d5-8f6a6b47a873, infoPort=51543, 
ipcPort=43490, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 1 msecs
   [junit4]   2> 702375 INFO  (IPC Server handler 3 on 40597) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=9328feef-42b3-4438-93d5-8f6a6b47a873, infoPort=51543, 
ipcPort=43490, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 702488 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log NO JSP Support for /, did not find 
org.apache.jasper.servlet.JspServlet
   [junit4]   2> 702685 INFO  
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[667D296926F4C3DA]-worker) [    
] o.m.log Started 
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58700
   [junit4]   2> 702766 INFO  (IPC Server handler 8 on 40597) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9 node DatanodeRegistration(127.0.0.1, 
datanodeUuid=9cecd7ad-a81c-4b52-ac9c-4fc217160cab, infoPort=58700, 
ipcPort=51060, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), 
blocks: 0, hasStaleStorages: true, processing time: 0 msecs
   [junit4]   2> 702767 INFO  (IPC Server handler 8 on 40597) [    ] 
BlockStateChange BLOCK* processReport: from storage 
DS-6c4f0860-7e86-434b-8e56-7c95c03ca83d node DatanodeRegistration(127.0.0.1, 
datanodeUuid=9cecd7ad-a81c-4b52-ac9c-4fc217160cab, infoPort=58700, 
ipcPort=51060, storageInfo=lv=-56;cid=testClusterID;nsid=887295706;c=0), 
blocks: 0, hasStaleStorages: false, processing time: 0 msecs
   [junit4]   2> 702857 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 702858 INFO  (Thread-10102) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 702858 INFO  (Thread-10102) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 702958 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkTestServer start zk server on port:52278
   [junit4]   2> 702958 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 702958 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 702961 INFO  (zkCallback-678-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@264e45a7 
name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 702962 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 702962 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 702962 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 702965 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 702967 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 702975 INFO  (zkCallback-679-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@e9c70f9 name:ZooKeeperConnection 
Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 702976 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 702976 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 702976 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1
   [junit4]   2> 702978 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
   [junit4]   2> 702981 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
   [junit4]   2> 702982 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
   [junit4]   2> 702985 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702986 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
   [junit4]   2> 702988 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702989 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
   [junit4]   2> 702990 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702991 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 702992 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702993 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
   [junit4]   2> 702994 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702994 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
   [junit4]   2> 702996 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702997 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
   [junit4]   2> 702999 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 702999 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
   [junit4]   2> 703001 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703001 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
   [junit4]   2> 703005 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703005 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
   [junit4]   2> 703007 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703007 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
   [junit4]   2> 703010 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703010 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
   [junit4]   2> 703013 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 703013 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 703015 INFO  (zkCallback-680-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@2e187782 
name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 703015 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 703015 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 703016 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /solr
   [junit4]   2> 703017 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 703018 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 703022 INFO  (zkCallback-681-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@52504684 
name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 703022 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 703022 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 703023 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703023 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml
   [junit4]   2> 703028 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703028 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml
   [junit4]   2> 703030 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703030 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml
   [junit4]   2> 703033 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703033 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: 
/configs/conf2/solrconfig.snippet.randomindexconfig.xml
   [junit4]   2> 703036 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703036 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt
   [junit4]   2> 703040 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703041 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt
   [junit4]   2> 703044 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703045 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml
   [junit4]   2> 703046 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703047 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json
   [junit4]   2> 703049 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703049 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt
   [junit4]   2> 703051 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703052 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt
   [junit4]   2> 703056 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703057 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt
   [junit4]   2> 703059 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703059 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml
   [junit4]   2> 703162 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/cores/collection1
   [junit4]   2> 703164 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 703167 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@523be601{/,null,AVAILABLE}
   [junit4]   2> 703168 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@5a7975d4{HTTP/1.1}{127.0.0.1:39347}
   [junit4]   2> 703168 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.Server Started @706876ms
   [junit4]   2> 703168 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: 
{solr.data.dir=hdfs://localhost:40597/hdfs__localhost_40597__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001_tempDir-002_control_data,
 hostPort=39347, hostContext=/, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores}
   [junit4]   2> 703169 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 703169 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/'
   [junit4]   2> 703193 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 703196 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 703197 INFO  (zkCallback-682-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@559a78ee 
name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 703197 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 703197 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 703199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 703199 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/solr.xml
   [junit4]   2> 703214 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/cores
   [junit4]   2> 703214 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1012968287
   [junit4]   2> 703214 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/]
   [junit4]   2> 703214 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/lib
   [junit4]   2> 703214 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/control-001/lib).
   [junit4]   2> 703223 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 703225 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 703225 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 703225 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 703226 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52278/solr
   [junit4]   2> 703226 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 703226 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 703226 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 703230 INFO  (zkCallback-684-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@555ee8aa 
name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 703230 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 703231 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 703234 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.ConnectionManager Waiting for client to 
connect to ZooKeeper
   [junit4]   2> 703235 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@617e6614 
name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 703235 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.ConnectionManager Client is connected to 
ZooKeeper
   [junit4]   2> 703236 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
   [junit4]   2> 703239 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-queue-work
   [junit4]   2> 703242 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-running
   [junit4]   2> 703244 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-completed
   [junit4]   2> 703248 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer/collection-map-failure
   [junit4]   2> 703254 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
   [junit4]   2> 703256 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
   [junit4]   2> 703257 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
   [junit4]   2> 703259 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /security.json
   [junit4]   2> 703260 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:39347_
   [junit4]   2> 703261 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/live_nodes/127.0.0.1:39347_
   [junit4]   2> 703262 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
   [junit4]   2> 703263 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: 
/overseer_elect/election
   [junit4]   2> 703267 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 703268 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.LeaderElector Joined leadership election with 
path: /overseer_elect/election/95160657043324934-127.0.0.1:39347_-n_0000000000
   [junit4]   2> 703270 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.OverseerElectionContext I am going to be the 
leader 127.0.0.1:39347_
   [junit4]   2> 703271 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
   [junit4]   2> 703273 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer Overseer 
(id=95160657043324934-127.0.0.1:39347_-n_0000000000) starting
   [junit4]   2> 703275 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
   [junit4]   2> 703287 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting 
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 
autoReplicaFailoverWaitAfterExpiration=10000 
autoReplicaFailoverBadNodeExpiration=60000
   [junit4]   2> 703289 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer Starting to work on the main queue
   [junit4]   2> 703289 INFO  
(OverseerCollectionProcessor-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.OverseerCollectionProcessor Process current 
queue of collection creations
   [junit4]   2> 703292 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.c.ZkStateReader Updating cluster state from 
ZooKeeper... 
   [junit4]   2> 703310 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.CoreContainer Security conf doesn't exist. 
Skipping setup for authorization module.
   [junit4]   2> 703311 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 703311 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] 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_667D296926F4C3DA-001/control-001/cores
   [junit4]   2> 703312 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.SolrCore Created CoreDescriptor: 
{absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1/,
 schema=schema.xml, collection=control_collection, 
instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/control-001/cores/collection1,
 shard=, coreNodeName=, dataDir=data/, transient=false, config=solrconfig.xml, 
name=collection1, loadOnStartup=true, configSetProperties=configsetprops.json}
   [junit4]   2> 703312 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] 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_667D296926F4C3DA-001/control-001/cores/collection1/
   [junit4]   2> 703313 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.c.CoresLocator Found 1 core definitions
   [junit4]   2> 703314 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
publishing state=down
   [junit4]   2> 703314 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
numShards not found on descriptor - reading it from system property
   [junit4]   2> 703314 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] 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> 703314 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) 
[n:127.0.0.1:39347_    ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() 
done
   [junit4]   2> 703314 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 703315 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
look for our core node name
   [junit4]   2> 703316 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "base_url":"http://127.0.0.1:39347";,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "node_name":"127.0.0.1:39347_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "operation":"state"} current state version: 0
   [junit4]   2> 703316 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "base_url":"http://127.0.0.1:39347";,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "state":"down",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "node_name":"127.0.0.1:39347_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "shard":null,
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 703317 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
control_collection
   [junit4]   2> 703317 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ReplicaMutator Assigning new node to shard 
shard=shard1
   [junit4]   2> 703318 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] 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> 703320 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 1
   [junit4]   2> 704316 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
waiting to find shard id in clusterstate for collection1
   [junit4]   2> 704316 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Check for collection zkNode:control_collection
   [junit4]   2> 704317 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.ZkController 
Collection zkNode exists
   [junit4]   2> 704317 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader Load collection config 
from:/collections/control_collection
   [junit4]   2> 704319 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 
specified config exists in ZooKeeper
   [junit4]   2> 704319 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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_667D296926F4C3DA-001/control-001/cores/collection1/'
   [junit4]   2> 704337 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.Config loaded 
config solrconfig.xml with version 0 
   [junit4]   2> 704343 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
current version of requestparams : -1
   [junit4]   2> 704356 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 5.3.2
   [junit4]   2> 704386 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.Config Loaded 
SolrConfig: solrconfig.xml
   [junit4]   2> 704387 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
Reading Solr Schema from /configs/conf1/schema.xml
   [junit4]   2> 704396 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
[collection1] Schema name=test
   [junit4]   2> 704682 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with 
rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 704691 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
default search field in schema is text
   [junit4]   2> 704693 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.s.IndexSchema 
unique key field: id
   [junit4]   2> 704708 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 704712 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 704715 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 704716 WARN  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 704716 WARN  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 704717 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from 
open-exchange-rates.json
   [junit4]   2> 704718 WARN  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 704718 WARN  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] 
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 704719 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704720 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection   x:collection1] o.a.s.c.CoreContainer 
Creating SolrCore 'collection1' using configuration from collection 
control_collection
   [junit4]   2> 704720 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory
   [junit4]   2> 704720 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.HdfsDirectoryFactory 
solr.hdfs.home=hdfs://localhost:40597/solr_hdfs_home
   [junit4]   2> 704720 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
   [junit4]   2> 704720 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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_667D296926F4C3DA-001/control-001/cores/collection1/],
 dataDir=[null]
   [junit4]   2> 704721 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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@6c045f01
   [junit4]   2> 704721 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.HdfsDirectoryFactory creating directory factory for path 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 704738 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.h.HdfsLocalityReporter Registering direcotry 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data for 
locality metrics.
   [junit4]   2> 704739 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data
   [junit4]   2> 704739 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore New index directory detected: old=null 
new=hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index/
   [junit4]   2> 704772 WARN  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore [collection1] Solr index directory 
'hdfs:/localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index' 
doesn't exist. Creating new index...
   [junit4]   2> 704772 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.HdfsDirectoryFactory creating directory factory for path 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 704786 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.h.HdfsLocalityReporter Registering direcotry 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index 
for locality metrics.
   [junit4]   2> 704786 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CachingDirectoryFactory return new directory for 
hdfs://localhost:40597/solr_hdfs_home/control_collection/core_node1/data/index
   [junit4]   2> 704787 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.10331445500101646]
   [junit4]   2> 704814 INFO  (IPC Server handler 9 on 40597) [    ] 
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35726 is 
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, 
replicas=[ReplicaUnderConstruction[[DISK]DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9:NORMAL:127.0.0.1:58734|RBW],
 
ReplicaUnderConstruction[[DISK]DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9:NORMAL:127.0.0.1:35726|FINALIZED]]}
 size 0
   [junit4]   2> 704816 INFO  (IPC Server handler 0 on 40597) [    ] 
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:58734 is 
added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, 
primaryNodeIndex=-1, 
replicas=[ReplicaUnderConstruction[[DISK]DS-de7571cc-db3f-4a85-8d82-baf368b2b2c9:NORMAL:127.0.0.1:58734|RBW],
 
ReplicaUnderConstruction[[DISK]DS-283cbc1e-c2ec-4e1b-9ff6-6059940e31d9:NORMAL:127.0.0.1:35726|FINALIZED]]}
 size 0
   [junit4]   2> 704820 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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@b6e0a4d7 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e7612c4; 
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 704820 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 704822 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39347_ 
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:40597/solr_hdfs_home/control_collection/core_node1/data/
   [junit4]   2> 704828 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"nodistrib"
   [junit4]   2> 704829 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"dedupe"
   [junit4]   2> 704829 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 704829 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain 
"stored_sig"
   [junit4]   2> 704829 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.p.UpdateRequestProcessorChain inserting 
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 704829 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704830 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704830 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704830 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704834 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 704835 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 704837 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 704838 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 704843 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.RequestHandlers Registered paths: 
/schema,/update/json,/admin/segments,/admin/system,/admin/properties,/get,/replication,/admin/file,/update/json/docs,standard,/admin/luke,/admin/ping,/admin/mbeans,/update/csv,/update,/admin/logging,/config,/admin/plugins,/admin/threads
   [junit4]   2> 704845 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704846 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704846 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704861 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 704861 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 704863 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=1369075563, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 704875 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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@b6e0a4d7 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4e7612c4; 
maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1}
   [junit4]   2> 704875 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 704875 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.s.SolrIndexSearcher Opening Searcher@4710f52b[collection1] main
   [junit4]   2> 704876 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704876 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704877 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704877 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf1
   [junit4]   2> 704877 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 704877 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704878 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704878 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704878 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 704878 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 704879 INFO  
(searcherExecutor-2520-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore 
[collection1] Registered new searcher Searcher@4710f52b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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 1522570514814468096
   [junit4]   2> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ 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 
1522570514814468096
   [junit4]   2> 704882 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ZkController watch zkdir /configs/conf1
   [junit4]   2> 704883 INFO  
(coreLoadExecutor-2519-thread-1-processing-n:127.0.0.1:39347_) 
[n:127.0.0.1:39347_ c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 704884 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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:39347 
collection:control_collection shard:shard1
   [junit4]   2> 704888 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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> 704892 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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/95160657043324934-core_node1-n_0000000000
   [junit4]   2> 704893 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
   [junit4]   2> 704895 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 704895 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] 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> 704896 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 704896 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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> 704896 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync replicas to http://127.0.0.1:39347/collection1/
   [junit4]   2> 704897 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] 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> 704897 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
Sync Success - now sync replicas to me
   [junit4]   2> 704897 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy 
http://127.0.0.1:39347/collection1/ has no replicas
   [junit4]   2> 704898 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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:39347/collection1/ shard1
   [junit4]   2> 704898 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] 
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
   [junit4]   2> 704899 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 2
   [junit4]   2> 704907 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 704908 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] 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:39347";,
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "state":"active"} current state version: 2
   [junit4]   2> 705012 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] 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> 705014 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 3
   [junit4]   2> 705059 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
We are http://127.0.0.1:39347/collection1/ and leader is 
http://127.0.0.1:39347/collection1/
   [junit4]   2> 705059 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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:39347
   [junit4]   2> 705059 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
I am the leader, no recovery necessary
   [junit4]   2> 705059 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController 
publishing state=active
   [junit4]   2> 705059 INFO  
(coreZkRegister-2513-thread-1-processing-x:collection1 c:control_collection 
n:127.0.0.1:39347_ r:core_node1 s:shard1) [n:127.0.0.1:39347_ 
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> 705061 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 705062 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "base_url":"http://127.0.0.1:39347";,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "state":"active",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "node_name":"127.0.0.1:39347_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "operation":"state"} current state version: 3
   [junit4]   2> 705062 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ReplicaMutator Update state numShards=1 
message={
   [junit4]   2>   "base_url":"http://127.0.0.1:39347";,
   [junit4]   2>   "roles":null,
   [junit4]   2>   "state":"active",
   [junit4]   2>   "collection":"control_collection",
   [junit4]   2>   "core":"collection1",
   [junit4]   2>   "node_name":"127.0.0.1:39347_",
   [junit4]   2>   "numShards":"1",
   [junit4]   2>   "core_node_name":"core_node1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "operation":"state"}
   [junit4]   2> 705165 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] 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> 705169 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 4
   [junit4]   2> 705318 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 705318 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 705320 INFO  (zkCallback-687-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@7d1e7e11 
name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 705321 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 705321 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 705321 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... 
   [junit4]   2> 705327 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection 
loss:false
   [junit4]   2> 705327 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
   [junit4]   2> 705327 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 705328 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 705331 INFO  (zkCallback-688-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@696e2c92 
name:ZooKeeperConnection Watcher:127.0.0.1:52278/solr got event WatchedEvent 
state:SyncConnected type:None path:null path:null type:None
   [junit4]   2> 705331 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 705331 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 705335 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue 
state SyncConnected
   [junit4]   2> 705336 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.Overseer processMessage: queueSize: 1, message 
= {
   [junit4]   2>   "operation":"create",
   [junit4]   2>   "name":"collection1",
   [junit4]   2>   "numShards":"2",
   [junit4]   2>   "stateFormat":"2"} current state version: 4
   [junit4]   2> 705337 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ClusterStateMutator building a new cName: 
collection1
   [junit4]   2> 705442 INFO  
(OverseerStateUpdate-95160657043324934-127.0.0.1:39347_-n_0000000000) 
[n:127.0.0.1:39347_    ] o.a.s.c.o.ZkStateWriter going to create_collection 
/collections/collection1/state.json
   [junit4]   2> 705442 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/cores/collection1
   [junit4]   2> 705443 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001
   [junit4]   2> 705443 INFO  (zkCallback-687-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> 705443 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] 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> 705444 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.Server jetty-9.2.11.v20150529
   [junit4]   2> 705447 INFO  (zkCallback-687-thread-1) [    ] 
o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 705447 INFO  
(zkCallback-685-thread-1-processing-n:127.0.0.1:39347_) [n:127.0.0.1:39347_    
] o.a.s.c.c.ZkStateReader Updated cluster state version to 5
   [junit4]   2> 705449 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6cc60395{/,null,AVAILABLE}
   [junit4]   2> 705450 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.ServerConnector Started 
ServerConnector@2015eedc{HTTP/1.1}{127.0.0.1:52230}
   [junit4]   2> 705450 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.e.j.s.Server Started @709158ms
   [junit4]   2> 705450 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, 
solr.data.dir=hdfs://localhost:40597/hdfs__localhost_40597__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001_tempDir-002_jetty1,
 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_667D296926F4C3DA-001/shard-1-001/cores,
 hostContext=/, hostPort=52230}
   [junit4]   2> 705450 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): 
sun.misc.Launcher$AppClassLoader@7b3cb2c6
   [junit4]   2> 705450 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/'
   [junit4]   2> 705470 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 705473 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 705474 INFO  (zkCallback-689-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@10a6fae name:ZooKeeperConnection 
Watcher:127.0.0.1:52278/solr got event WatchedEvent state:SyncConnected 
type:None path:null path:null type:None
   [junit4]   2> 705474 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 705474 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkACLProvider
   [junit4]   2> 705476 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in 
ZooKeeper)
   [junit4]   2> 705476 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/solr.xml
   [junit4]   2> 705499 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/cores
   [junit4]   2> 705499 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.CoreContainer New CoreContainer 1064714485
   [junit4]   2> 705499 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/]
   [junit4]   2> 705499 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/lib
   [junit4]   2> 705499 WARN  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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_667D296926F4C3DA-001/shard-1-001/lib).
   [junit4]   2> 705514 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
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> 705515 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 705515 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 705516 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 705516 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52278/solr
   [junit4]   2> 705516 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.ZkController zkHost includes chroot
   [junit4]   2> 705516 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
   [junit4]   2> 705517 INFO  
(TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[667D296926F4C3DA]) [    ] 
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 705518 INFO  (zkCallback-691-thread-1) [    ] 
o.a.s.c.c.ConnectionManager Watcher 
org.apache.solr.common.cloud.ConnectionManager@11d0104e 
name:ZooKeeperConnection Watcher:127.0.0.1:52278 got event WatchedEvent state

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

ockTreeOrds(blocksize=128), 1parent_f2_s=Lucene50(blocksize=128), 
16parent_f2_s=Lucene50(blocksize=128), 
42parent_f1_s=PostingsFormat(name=Direct), 
12parent_f2_s=Lucene50(blocksize=128), 
4grand1_s=PostingsFormat(name=Asserting), 
9grand6_s=BlockTreeOrds(blocksize=128), 38parent_f2_s=Lucene50(blocksize=128), 
2parent_f1_s=PostingsFormat(name=Direct), 
43parent_f1_s=PostingsFormat(name=Asserting), 
17parent_f2_s=PostingsFormat(name=Asserting), 
50parent_f1_s=PostingsFormat(name=Asserting), 
6grand2_s=Lucene50(blocksize=128), 
22parent_f2_s=PostingsFormat(name=Asserting), 
9grand0_s=PostingsFormat(name=Asserting), 
45parent_f2_s=Lucene50(blocksize=128), 
3grand0_s=PostingsFormat(name=Asserting), 4child0_s=Lucene50(blocksize=128), 
5child4_s=PostingsFormat(name=Asserting), 
1parent_f1_s=PostingsFormat(name=Asserting), 5grand3_s=Lucene50(blocksize=128), 
49parent_f2_s=Lucene50(blocksize=128), 
9parent_f1_s=PostingsFormat(name=Asserting), 3child1_s=Lucene50(blocksize=128), 
6parent_f2_s=PostingsFormat(name=Asserting), 
2parent_f2_s=PostingsFormat(name=Asserting), 
35parent_f1_s=PostingsFormat(name=Direct), 8grand0_s=Lucene50(blocksize=128), 
5parent_f1_s=PostingsFormat(name=Asserting), 
3parent_f2_s=PostingsFormat(name=Direct), 
40parent_f1_s=Lucene50(blocksize=128), 15parent_f1_s=Lucene50(blocksize=128), 
7grand3_s=PostingsFormat(name=Direct), 
32parent_f2_s=PostingsFormat(name=Direct), 
42parent_f2_s=BlockTreeOrds(blocksize=128), 
48parent_f2_s=PostingsFormat(name=Asserting), 
7parent_f2_s=PostingsFormat(name=Direct), 
21parent_f1_s=PostingsFormat(name=Asserting), 
6grand3_s=PostingsFormat(name=Asserting), 5child3_s=Lucene50(blocksize=128), 
rnd_b=BlockTreeOrds(blocksize=128), 49parent_f1_s=BlockTreeOrds(blocksize=128), 
23parent_f1_s=BlockTreeOrds(blocksize=128), 
20parent_f1_s=PostingsFormat(name=Direct), 
7grand4_s=PostingsFormat(name=Asserting), 0parent_f1_s=Lucene50(blocksize=128), 
a_t=PostingsFormat(name=Direct), 29parent_f1_s=BlockTreeOrds(blocksize=128), 
31parent_f1_s=PostingsFormat(name=Direct), 
40parent_f2_s=PostingsFormat(name=Asserting), 
39parent_f1_s=PostingsFormat(name=Direct), 
44parent_f1_s=Lucene50(blocksize=128), 1child0_s=BlockTreeOrds(blocksize=128), 
9grand8_s=PostingsFormat(name=Asserting), 7grand5_s=Lucene50(blocksize=128), 
5child0_s=PostingsFormat(name=Asserting), 
5grand4_s=BlockTreeOrds(blocksize=128), 
21parent_f2_s=PostingsFormat(name=Direct), 
2grand1_s=PostingsFormat(name=Asserting), 
8grand5_s=PostingsFormat(name=Asserting), 
7grand2_s=BlockTreeOrds(blocksize=128), 
46parent_f1_s=PostingsFormat(name=Direct), 
24parent_f2_s=BlockTreeOrds(blocksize=128), 
8grand1_s=PostingsFormat(name=Asserting), 
18parent_f2_s=PostingsFormat(name=Direct), 
3grand2_s=BlockTreeOrds(blocksize=128), 
12parent_f1_s=BlockTreeOrds(blocksize=128), 
11parent_f1_s=Lucene50(blocksize=128), 3grand1_s=Lucene50(blocksize=128), 
45parent_f1_s=BlockTreeOrds(blocksize=128), 
18parent_f1_s=BlockTreeOrds(blocksize=128), 
46parent_f2_s=BlockTreeOrds(blocksize=128), 
19parent_f2_s=BlockTreeOrds(blocksize=128), 
2child1_s=BlockTreeOrds(blocksize=128), id=BlockTreeOrds(blocksize=128), 
36parent_f1_s=PostingsFormat(name=Asserting), 
25parent_f2_s=PostingsFormat(name=Direct), 
44parent_f2_s=PostingsFormat(name=Asserting), 
38parent_f1_s=BlockTreeOrds(blocksize=128), 
6parent_f1_s=PostingsFormat(name=Direct), 
43parent_f2_s=PostingsFormat(name=Direct), 
14parent_f2_s=PostingsFormat(name=Direct), 
22parent_f1_s=Lucene50(blocksize=128), 8grand3_s=BlockTreeOrds(blocksize=128), 
4grand3_s=BlockTreeOrds(blocksize=128), 
26parent_f2_s=PostingsFormat(name=Asserting), 
31parent_f2_s=BlockTreeOrds(blocksize=128), 
4parent_f1_s=Lucene50(blocksize=128), 
17parent_f1_s=PostingsFormat(name=Direct), 
5parent_f2_s=Lucene50(blocksize=128), _root_=PostingsFormat(name=Direct), 
5grand2_s=PostingsFormat(name=Asserting), 
14parent_f1_s=PostingsFormat(name=Asserting), 
6grand5_s=BlockTreeOrds(blocksize=128), 
33parent_f2_s=PostingsFormat(name=Asserting), 
11parent_f2_s=PostingsFormat(name=Asserting), 
27parent_f1_s=BlockTreeOrds(blocksize=128), 
47parent_f2_s=PostingsFormat(name=Direct), 
13parent_f2_s=BlockTreeOrds(blocksize=128), 
9grand4_s=PostingsFormat(name=Asserting), 
9grand2_s=BlockTreeOrds(blocksize=128), 9grand1_s=PostingsFormat(name=Direct), 
8parent_f1_s=Lucene50(blocksize=128), 4child2_s=PostingsFormat(name=Direct), 
9grand7_s=Lucene50(blocksize=128), 4grand0_s=Lucene50(blocksize=128), 
30parent_f1_s=BlockTreeOrds(blocksize=128), 
50parent_f2_s=PostingsFormat(name=Direct), 
25parent_f1_s=PostingsFormat(name=Asserting), 
8grand6_s=PostingsFormat(name=Direct), 
10parent_f1_s=PostingsFormat(name=Asserting), 
28parent_f2_s=PostingsFormat(name=Asserting), 
9grand5_s=PostingsFormat(name=Direct), 26parent_f1_s=Lucene50(blocksize=128), 
3child2_s=BlockTreeOrds(blocksize=128), 8grand7_s=BlockTreeOrds(blocksize=128), 
47parent_f1_s=PostingsFormat(name=Asserting), 
4child3_s=PostingsFormat(name=Asserting), 
3parent_f1_s=BlockTreeOrds(blocksize=128), 
37parent_f1_s=Lucene50(blocksize=128), 
3child0_s=PostingsFormat(name=Asserting), 
1grand0_s=PostingsFormat(name=Asserting), 
7grand6_s=BlockTreeOrds(blocksize=128), 
39parent_f2_s=BlockTreeOrds(blocksize=128), 
27parent_f2_s=Lucene50(blocksize=128), 
32parent_f1_s=PostingsFormat(name=Asserting), 
_version_=PostingsFormat(name=Direct), 19parent_f1_s=Lucene50(blocksize=128), 
7grand1_s=Lucene50(blocksize=128), 6grand0_s=PostingsFormat(name=Direct), 
13parent_f1_s=PostingsFormat(name=Direct), 
5child2_s=BlockTreeOrds(blocksize=128), 
4parent_f2_s=BlockTreeOrds(blocksize=128), 
0parent_f2_s=BlockTreeOrds(blocksize=128), 
9parent_f2_s=Lucene50(blocksize=128), 8grand2_s=PostingsFormat(name=Direct), 
34parent_f2_s=Lucene50(blocksize=128), 5child1_s=PostingsFormat(name=Direct), 
10parent_f2_s=PostingsFormat(name=Direct), 
6grand4_s=PostingsFormat(name=Direct), text_t=PostingsFormat(name=Direct), 
5grand1_s=PostingsFormat(name=Direct), 33parent_f1_s=Lucene50(blocksize=128), 
41parent_f2_s=Lucene50(blocksize=128), 23parent_f2_s=Lucene50(blocksize=128), 
8grand4_s=Lucene50(blocksize=128), 
37parent_f2_s=PostingsFormat(name=Asserting), 
2grand0_s=PostingsFormat(name=Direct), 9grand3_s=Lucene50(blocksize=128), 
35parent_f2_s=BlockTreeOrds(blocksize=128), 
34parent_f1_s=BlockTreeOrds(blocksize=128), 
16parent_f1_s=PostingsFormat(name=Asserting), 
8parent_f2_s=BlockTreeOrds(blocksize=128), 
7grand0_s=PostingsFormat(name=Asserting)}, docValues:{}, 
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=es_PE, 
timezone=Etc/GMT-6
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=4,threads=1,free=129101448,total=451411968
   [junit4]   2> NOTE: All tests run in this JVM: [HdfsBasicDistributedZk2Test, 
DirectUpdateHandlerTest, MultiThreadedOCPTest, TestObjectReleaseTracker, 
TestSchemaManager, BadIndexSchemaTest, TestSolrCLIRunExample, 
SpellCheckCollatorTest, TestManagedSynonymFilterFactory, 
SegmentsInfoRequestHandlerTest, CleanupOldIndexTest, SearchHandlerTest, 
DistributedFacetPivotSmallAdvancedTest, DeleteReplicaTest, RankQueryTest, 
TestExtendedDismaxParser, TestCustomSort, TestLuceneMatchVersion, 
QueryEqualityTest, TestCollapseQParserPlugin, OverseerTest, TestBulkSchemaAPI, 
TestHighFrequencyDictionaryFactory, DistributedExpandComponentTest, 
ChangedSchemaMergeTest, AddSchemaFieldsUpdateProcessorFactoryTest, 
TestSolrConfigHandler, TestCollationFieldDocValues, TestQueryUtils, 
SimpleFacetsTest, TestExclusionRuleCollectionAccess, AddBlockUpdateTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, XmlUpdateRequestHandlerTest, 
SolrInfoMBeanTest, TestSchemaSimilarityResource, HardAutoCommitTest, 
TestAuthenticationFramework, TestReload, OverseerCollectionProcessorTest, 
BinaryUpdateRequestHandlerTest, TestPivotHelperCode, 
DocExpirationUpdateProcessorFactoryTest, PluginInfoTest, 
ConcurrentDeleteAndCreateCollectionTest, TestHdfsUpdateLog, 
MoreLikeThisHandlerTest, TestTolerantSearch, LeaderElectionTest, SampleTest, 
SynonymTokenizerTest, TestPerFieldSimilarity, DOMUtilTest, 
HdfsCollectionsAPIDistributedZkTest, FullSolrCloudDistribCmdsTest]
   [junit4] Completed [159/533] on J2 in 201.49s, 1 test, 1 error <<< FAILURES!

[...truncated 1142 lines...]
   [junit4] JVM J2: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160105_231446_195.sysout
   [junit4] >>> JVM J2: stdout (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: GC overhead limit exceeded
   [junit4] Dumping heap to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid10560.hprof
 ...
   [junit4] Heap dump file created [556370375 bytes in 7.665 secs]
   [junit4] <<< JVM J2: EOF ----

[...truncated 14 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, 2104 tests, 2 errors, 26 ignored (14 
assumptions)

Total time: 173 minutes 17 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