Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/3/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=70712, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=70712, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:60289/ncui/fx: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([1A45BFDA322A7443]: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 11754 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/init-core-data-001 [junit4] 2> 4358735 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 4358736 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ncui/fx [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 4358790 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 4358809 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4358817 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4358859 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-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_58662_hdfs____ca27x5/webapp [junit4] 2> 4359028 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4359309 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:58662 [junit4] 2> 4359382 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4359384 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4359397 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-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_48195_datanode____d2qve8/webapp [junit4] 2> 4359519 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4359838 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48195 [junit4] 2> 4360023 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4360025 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4360038 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-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_56246_datanode____.rtz9uu/webapp [junit4] 2> 4360106 INFO (IPC Server handler 2 on 54559) [ ] BlockStateChange BLOCK* processReport: from storage DS-e4d14440-307f-4461-9b78-c992db4165a0 node DatanodeRegistration(127.0.0.1, datanodeUuid=92a438e7-66a8-4f88-a159-58a2c9840a3e, infoPort=48195, ipcPort=36741, storageInfo=lv=-56;cid=testClusterID;nsid=1980791167;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4360107 INFO (IPC Server handler 2 on 54559) [ ] BlockStateChange BLOCK* processReport: from storage DS-221509a0-0b83-4da0-9917-a66ef04e9273 node DatanodeRegistration(127.0.0.1, datanodeUuid=92a438e7-66a8-4f88-a159-58a2c9840a3e, infoPort=48195, ipcPort=36741, storageInfo=lv=-56;cid=testClusterID;nsid=1980791167;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4360237 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4360514 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[1A45BFDA322A7443]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:56246 [junit4] 2> 4360739 INFO (IPC Server handler 4 on 54559) [ ] BlockStateChange BLOCK* processReport: from storage DS-81fa4da1-3b10-4265-b851-60199ed73262 node DatanodeRegistration(127.0.0.1, datanodeUuid=77fc654a-73a1-4922-8988-baf4cd1064a1, infoPort=56246, ipcPort=37231, storageInfo=lv=-56;cid=testClusterID;nsid=1980791167;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4360740 INFO (IPC Server handler 4 on 54559) [ ] BlockStateChange BLOCK* processReport: from storage DS-c073b0ff-9ac6-415a-a43c-72689d8b4652 node DatanodeRegistration(127.0.0.1, datanodeUuid=77fc654a-73a1-4922-8988-baf4cd1064a1, infoPort=56246, ipcPort=37231, storageInfo=lv=-56;cid=testClusterID;nsid=1980791167;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4360986 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4360990 INFO (Thread-54863) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4360990 INFO (Thread-54863) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4361090 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkTestServer start zk server on port:44730 [junit4] 2> 4361090 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361129 INFO (zkCallback-2519-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49251ce9 name:ZooKeeperConnection Watcher:127.0.0.1:44730 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4361133 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361152 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361181 INFO (zkCallback-2520-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1704d306 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 4361183 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 4361184 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 4361186 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 4361187 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361187 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4361189 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361190 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 4361191 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361192 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4361193 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361193 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 4361195 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361195 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 4361196 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361197 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 4361198 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361198 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4361200 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361200 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4361201 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4361203 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361203 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4361205 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361205 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 4361207 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361232 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361241 INFO (zkCallback-2521-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25c88ee0 name:ZooKeeperConnection Watcher:127.0.0.1:44730 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4361253 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361268 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361277 INFO (zkCallback-2522-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a7f7203 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361277 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361277 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 4361281 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361281 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 4361283 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361283 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 4361284 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361284 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4361286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 4361287 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361288 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 4361289 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361289 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 4361291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361291 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 4361293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 4361294 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361295 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 4361296 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361297 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 4361298 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361298 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 4361494 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1 [junit4] 2> 4361495 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 4361497 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6743667f{/ncui/fx,null,AVAILABLE} [junit4] 2> 4361498 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.ServerConnector Started ServerConnector@276b849b{HTTP/1.1}{127.0.0.1:55486} [junit4] 2> 4361498 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.Server Started @4364719ms [junit4] 2> 4361498 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores, hostPort=55486, hostContext=/ncui/fx, solr.data.dir=hdfs://localhost:54559/hdfs__localhost_54559__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001_tempDir-002_control_data} [junit4] 2> 4361498 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 4361499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/' [junit4] 2> 4361516 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361517 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361518 INFO (zkCallback-2523-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@55750a88 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361520 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4361520 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/solr.xml [junit4] 2> 4361538 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores [junit4] 2> 4361538 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.CoreContainer New CoreContainer 613919296 [junit4] 2> 4361539 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/] [junit4] 2> 4361539 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/lib [junit4] 2> 4361539 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/lib). [junit4] 2> 4361548 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4361549 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4361550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4361550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4361550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44730/solr [junit4] 2> 4361550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4361550 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4361551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361552 INFO (zkCallback-2525-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@162a0657 name:ZooKeeperConnection Watcher:127.0.0.1:44730 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361552 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4361554 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4361555 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@79738092 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4361555 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4361556 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 4361558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 4361560 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 4361561 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 4361563 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 4361565 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 4361566 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 4361567 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 4361568 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 4361569 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55486_ncui%2Ffx [junit4] 2> 4361569 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55486_ncui%2Ffx [junit4] 2> 4361576 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 4361577 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 4361578 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4361579 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000 [junit4] 2> 4361579 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55486_ncui%2Ffx [junit4] 2> 4361580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 4361581 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.Overseer Overseer (id=95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) starting [junit4] 2> 4361582 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 4361587 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4361593 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4361601 INFO (OverseerCollectionProcessor-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 4361607 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4361635 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4361635 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4361636 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores [junit4] 2> 4361641 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.SolrCore Created CoreDescriptor: {instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1, transient=false, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1/, config=solrconfig.xml, coreNodeName=, name=collection1, schema=schema.xml, shard=, collection=control_collection, configSetProperties=configsetprops.json, loadOnStartup=true, dataDir=data/} [junit4] 2> 4361642 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1/ [junit4] 2> 4361642 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4361649 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4361650 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4361661 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4361661 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4361662 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:55486/ncui/fx";, [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:55486_ncui%2Ffx", [junit4] 2> "numShards":"1"} current state version: 0 [junit4] 2> 4361663 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:55486/ncui/fx";, [junit4] 2> "operation":"state", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:55486_ncui%2Ffx", [junit4] 2> "numShards":"1"} [junit4] 2> 4361663 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4361663 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4361664 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] 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> 4361666 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 4361669 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1 [junit4] 2> 4361669 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4362661 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4362662 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 4362663 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4362663 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 4362664 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4362664 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1/' [junit4] 2> 4362682 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4362689 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4362703 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2 [junit4] 2> 4362733 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 4362734 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4362744 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4363178 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4363208 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4363211 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4363233 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4363236 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4363238 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4363239 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4363239 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4363240 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4363240 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4363240 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4363241 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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/J1 [junit4] 2> 4363242 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4363242 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 4363242 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:54559/solr_hdfs_home [junit4] 2> 4363242 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 4363242 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 4363243 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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@42984c6 [junit4] 2> 4363243 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4363281 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 4363281 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4363292 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 4363313 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:54559/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 4363313 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4363346 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 4363346 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:54559/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4363346 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=68.173828125, floorSegmentMB=1.98828125, forceMergeDeletesPctAllowed=1.85970783739837, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4363366 INFO (IPC Server handler 3 on 54559) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43026 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-81fa4da1-3b10-4265-b851-60199ed73262:NORMAL:127.0.0.1:55825|RBW], ReplicaUnderConstruction[[DISK]DS-e4d14440-307f-4461-9b78-c992db4165a0:NORMAL:127.0.0.1:43026|FINALIZED]]} size 0 [junit4] 2> 4363368 INFO (IPC Server handler 2 on 54559) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55825 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-81fa4da1-3b10-4265-b851-60199ed73262:NORMAL:127.0.0.1:55825|RBW], ReplicaUnderConstruction[[DISK]DS-e4d14440-307f-4461-9b78-c992db4165a0:NORMAL:127.0.0.1:43026|FINALIZED]]} size 0 [junit4] 2> 4363372 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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@b87fdd79 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d9e75e0; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4363372 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4363374 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55486_ncui%2Ffx 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:54559/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 4363378 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4363379 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 4363379 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4363379 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4363379 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4363379 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363380 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363380 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363380 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363383 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4363384 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4363385 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4363387 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4363391 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/system,/replication,/update/csv,/admin/file,/schema,/admin/mbeans,/update/json,/admin/luke,/config,/admin/segments,/admin/threads,standard,/admin/logging,/admin/plugins,/update/json/docs,/update,/admin/ping,/get,/admin/properties [junit4] 2> 4363393 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363393 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363393 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363405 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 4363405 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 4363406 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=27, maxMergedSegmentMB=32.40234375, floorSegmentMB=0.8779296875, forceMergeDeletesPctAllowed=8.594876719264965, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4363414 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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@b87fdd79 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6d9e75e0; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4363414 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4363415 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@714991f3[collection1] main [junit4] 2> 4363415 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363416 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363416 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363417 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4363417 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 4363417 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363418 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363418 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363418 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 4363418 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4363419 INFO (searcherExecutor-7524-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@714991f3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4363429 WARN (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Could not retrieve locality information for hdfs://localhost:39416/solr3 due to exception: java.net.ConnectException: Call From lucene1-us-west/10.41.0.5 to localhost:39416 failed on connection exception: java.net.ConnectException: Connection refused; For more details see: http://wiki.apache.org/hadoop/ConnectionRefused [junit4] 2> 4363430 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363431 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363431 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363431 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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 1522399109408882688 [junit4] 2> 4363431 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx 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 1522399109408882688 [junit4] 2> 4363431 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 4363432 INFO (coreLoadExecutor-7523-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 4363432 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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:55486/ncui/fx collection:control_collection shard:shard1 [junit4] 2> 4363433 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363436 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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/95149944178212870-core_node1-n_0000000000 [junit4] 2> 4363437 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 4363439 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4363439 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4363439 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363439 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] 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> 4363439 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55486/ncui/fx/collection1/ [junit4] 2> 4363440 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4363440 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55486/ncui/fx/collection1/ has no replicas [junit4] 2> 4363440 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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:55486/ncui/fx/collection1/ shard1 [junit4] 2> 4363440 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4363440 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] 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> 4363443 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 4363446 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4363447 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] 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:55486/ncui/fx";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 4363550 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] 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> 4363552 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 4363598 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:55486/ncui/fx/collection1/ and leader is http://127.0.0.1:55486/ncui/fx/collection1/ [junit4] 2> 4363598 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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:55486/ncui/fx [junit4] 2> 4363599 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4363599 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 4363599 INFO (coreZkRegister-7517-thread-1-processing-s:shard1 x:collection1 n:127.0.0.1:55486_ncui%2Ffx c:control_collection r:core_node1) [n:127.0.0.1:55486_ncui%2Ffx 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> 4363600 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4363602 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:55486/ncui/fx";, [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:55486_ncui%2Ffx", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1"} current state version: 3 [junit4] 2> 4363602 INFO (OverseerStateUpdate-95149944178212870-127.0.0.1:55486_ncui%2Ffx-n_0000000000) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:55486/ncui/fx";, [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:55486_ncui%2Ffx", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "numShards":"1"} [junit4] 2> 4363672 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4363673 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4363675 INFO (zkCallback-2528-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b3b8598 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4363677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4363677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4363677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4363680 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4363705 INFO (zkCallback-2528-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> 4363705 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] 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> 4363708 INFO (zkCallback-2528-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 4363709 INFO (zkCallback-2526-thread-1-processing-n:127.0.0.1:55486_ncui%2Ffx) [n:127.0.0.1:55486_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 4363848 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/cores/collection1 [junit4] 2> 4363849 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001 [junit4] 2> 4363857 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 4363969 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47535306{/ncui/fx,null,AVAILABLE} [junit4] 2> 4363970 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2f6fd37f{HTTP/1.1}{127.0.0.1:60289} [junit4] 2> 4363970 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.e.j.s.Server Started @4367191ms [junit4] 2> 4363970 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/cores, hostContext=/ncui/fx, hostPort=60289, solr.data.dir=hdfs://localhost:54559/hdfs__localhost_54559__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001_tempDir-002_jetty1} [junit4] 2> 4363970 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 4363970 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/' [junit4] 2> 4364000 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4364024 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4364041 INFO (zkCallback-2529-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5aa84432 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4364041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4364041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4364042 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4364043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/solr.xml [junit4] 2> 4364059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/cores [junit4] 2> 4364059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.CoreContainer New CoreContainer 623451521 [junit4] 2> 4364060 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/] [junit4] 2> 4364060 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/lib [junit4] 2> 4364060 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_1A45BFDA322A7443-001/shard-1-001/lib). [junit4] 2> 4364073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] 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> 4364075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4364075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4364075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4364076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44730/solr [junit4] 2> 4364076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4364076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4364076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4364078 INFO (zkCallback-2531-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@652fedbd name:ZooKeeperConnection Watcher:127.0.0.1:44730 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4364078 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4364078 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4364080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4364082 INFO (zkCallback-2532-thread-1-processing-n:127.0.0.1:60289_ncui%2Ffx) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28ab91b9 name:ZooKeeperConnection Watcher:127.0.0.1:44730/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4364082 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4364087 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4365091 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60289_ncui%2Ffx [junit4] 2> 4365092 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60289_ncui%2Ffx [junit4] 2> 4365096 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[1A45BFDA322A7443]) [n:127.0.0.1:60289_ncui%2Ffx ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4365096 INFO (TEST-HdfsCollectionsAPIDistribut [...truncated too long message...] tlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/build/analysis/icu/lucene-analyzers-icu-5.3.2-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.7/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160104_004923_185.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J2-20160104_004923_185.suites [junit4] ERROR: JVM J2 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1504) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:262) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [junit4] at java.lang.Thread.run(Thread.java:745) 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: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 232 minutes 14 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
