Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/728/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=3786, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3786, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:56756: collection already exists: awholynewstresscollection_collection3_1 at __randomizedtesting.SeedInfo.seed([910494FBD3B3A55D]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=3862, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=3862, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33527: Could not find collection : awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([910494FBD3B3A55D]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10204 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/init-core-data-001 [junit4] 2> 349441 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 349442 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 350138 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 350870 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 351061 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog [junit4] 2> 351072 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 351156 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 351210 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-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_40867_hdfs____48zl47/webapp [junit4] 2> 351371 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 352050 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40867 [junit4] 2> 352923 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 352931 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 352944 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-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_50072_datanode____.wplcax/webapp [junit4] 2> 353054 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 354095 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50072 [junit4] 2> 354210 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 354212 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 354231 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-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_54002_datanode____.7ygacm/webapp [junit4] 2> 354345 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 354859 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[910494FBD3B3A55D]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54002 [junit4] 2> 355140 INFO (IPC Server handler 8 on 44293) [ ] BlockStateChange BLOCK* processReport: from storage DS-aacb32a9-0861-487e-822c-7e666212dc17 node DatanodeRegistration(127.0.0.1, datanodeUuid=6dde9bbe-8bbd-447a-86c3-9ee1ed39e998, infoPort=54002, ipcPort=53941, storageInfo=lv=-56;cid=testClusterID;nsid=35954857;c=0), blocks: 0, hasStaleStorages: true, processing time: 3 msecs [junit4] 2> 355141 INFO (IPC Server handler 7 on 44293) [ ] BlockStateChange BLOCK* processReport: from storage DS-2fccec29-6ae2-4acd-9642-393092044333 node DatanodeRegistration(127.0.0.1, datanodeUuid=cddb33c4-ca69-4179-bfa7-c8ce1d7e4f43, infoPort=50072, ipcPort=36335, storageInfo=lv=-56;cid=testClusterID;nsid=35954857;c=0), blocks: 0, hasStaleStorages: true, processing time: 2 msecs [junit4] 2> 355142 INFO (IPC Server handler 8 on 44293) [ ] BlockStateChange BLOCK* processReport: from storage DS-458c0b94-822a-4cc6-9111-137fd410b87e node DatanodeRegistration(127.0.0.1, datanodeUuid=6dde9bbe-8bbd-447a-86c3-9ee1ed39e998, infoPort=54002, ipcPort=53941, storageInfo=lv=-56;cid=testClusterID;nsid=35954857;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 355144 INFO (IPC Server handler 7 on 44293) [ ] BlockStateChange BLOCK* processReport: from storage DS-3a8cc705-b72b-4455-9dce-b5dc8c6aeb28 node DatanodeRegistration(127.0.0.1, datanodeUuid=cddb33c4-ca69-4179-bfa7-c8ce1d7e4f43, infoPort=50072, ipcPort=36335, storageInfo=lv=-56;cid=testClusterID;nsid=35954857;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 355689 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 355690 INFO (Thread-1021) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 355690 INFO (Thread-1021) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 355790 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkTestServer start zk server on port:45348 [junit4] 2> 355790 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 355791 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 355794 INFO (zkCallback-296-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5250c75e name:ZooKeeperConnection Watcher:127.0.0.1:45348 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 355794 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 355795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 355795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 355800 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 355800 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 355804 INFO (zkCallback-297-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7a282bd3 name:ZooKeeperConnection Watcher:127.0.0.1:45348/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 355804 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 355804 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 355804 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 355807 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 355809 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 355811 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 355812 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 355813 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 355815 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 355815 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 355817 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 355817 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 355819 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 355819 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 355821 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 355821 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 355822 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 355823 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 355824 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 355824 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 355826 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 355826 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 355828 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 355828 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 355833 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 355834 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 355837 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 355838 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 355842 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 355844 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 355844 INFO (zkCallback-298-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c48b072 name:ZooKeeperConnection Watcher:127.0.0.1:45348/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 355845 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 355845 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 355847 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x14e482947250002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 356286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1 [junit4] 2> 356288 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 356298 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cc506e8{/,null,AVAILABLE} [junit4] 2> 356301 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@15ec4f85{SSL-http/1.1}{127.0.0.1:47553} [junit4] 2> 356301 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.Server Started @359818ms [junit4] 2> 356301 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:44293/hdfs__localhost_44293__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001_tempDir-002_control_data, hostContext=/, hostPort=47553, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores} [junit4] 2> 356303 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 356303 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/' [junit4] 2> 356323 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/solr.xml [junit4] 2> 356330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores [junit4] 2> 356330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1942248414 [junit4] 2> 356330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/] [junit4] 2> 356330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/lib [junit4] 2> 356331 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/lib). [junit4] 2> 356338 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] 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> 356340 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 356342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 356342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 356342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45348/solr [junit4] 2> 356342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 356342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 356349 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 356352 INFO (zkCallback-300-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b741957 name:ZooKeeperConnection Watcher:127.0.0.1:45348 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 356356 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 356358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 356367 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 356379 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15474cf6 name:ZooKeeperConnection Watcher:127.0.0.1:45348/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 356379 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 356380 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 356382 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 356384 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 356385 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 356387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 356389 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 356390 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 356391 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 356392 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47553_ [junit4] 2> 356392 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:47553_ [junit4] 2> 356393 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 356394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 356395 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 356396 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94091984345825284-127.0.0.1:47553_-n_0000000000 [junit4] 2> 356396 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:47553_ [junit4] 2> 356397 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 356403 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer Overseer (id=94091984345825284-127.0.0.1:47553_-n_0000000000) starting [junit4] 2> 356408 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 356413 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 356413 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 356416 INFO (OverseerCollectionProcessor-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 356421 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 356423 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 356423 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 356424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores [junit4] 2> 356425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 356425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1/ [junit4] 2> 356425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 356426 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 356426 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 356427 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 356432 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 356433 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 356433 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:47553_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 356433 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:47553";, [junit4] 2> "node_name":"127.0.0.1:47553_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 356434 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:47553";, [junit4] 2> "node_name":"127.0.0.1:47553_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 356434 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 356434 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 356435 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] 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> 356437 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 357427 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 357427 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 357428 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 357428 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 357429 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 357429 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1/' [junit4] 2> 357443 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 357454 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 357458 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 357465 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 357466 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 357477 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 357576 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 357583 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 357584 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 357592 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 357595 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 357596 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 357597 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 357597 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 357597 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 357598 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 357598 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 357598 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 357599 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 357614 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:44293/solr_hdfs_home [junit4] 2> 357614 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 357614 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 357614 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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@3f8e83f8 [junit4] 2> 357615 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 357716 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 362722 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 367804 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 367805 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 367811 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 367869 WARN (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:44293/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 367870 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 367922 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 367922 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:44293/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 367922 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@54555e1b [junit4] 2> 368398 INFO (IPC Server handler 0 on 44293) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39592 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2fccec29-6ae2-4acd-9642-393092044333:NORMAL:127.0.0.1:52675|RBW], ReplicaUnderConstruction[[DISK]DS-aacb32a9-0861-487e-822c-7e666212dc17:NORMAL:127.0.0.1:39592|RBW]]} size 71 [junit4] 2> 368402 INFO (IPC Server handler 9 on 44293) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:52675 is added to blk_1073741825_1001 size 71 [junit4] 2> 368801 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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@b74e8f87 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1446063f; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 368801 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 368805 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:47553_ 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:44293/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 368809 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 368809 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368810 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368811 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368812 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 368813 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 368814 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 368815 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 368819 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 368821 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368833 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368834 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368857 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 368857 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 368860 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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=42, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=60.1083984375, floorSegmentMB=1.8310546875, forceMergeDeletesPctAllowed=20.83673556511934, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3631659373354156 [junit4] 2> 368978 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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@b74e8f87 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1446063f; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 368978 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 368978 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7bd16f89[collection1] main [junit4] 2> 368978 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368983 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368983 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368983 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 368983 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 368984 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368984 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368984 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 368985 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 368985 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 368993 INFO (searcherExecutor-1019-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7bd16f89[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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 1505471763493945344 [junit4] 2> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ 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 1505471763493945344 [junit4] 2> 369006 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 369007 INFO (coreLoadExecutor-1018-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 369008 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:47553 collection:control_collection shard:shard1 [junit4] 2> 369009 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ 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> 369014 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ 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/94091984345825284-core_node1-n_0000000000 [junit4] 2> 369015 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 369018 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 369018 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 369018 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] 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> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ 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> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:47553/collection1/ [junit4] 2> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:47553/collection1/ has no replicas [junit4] 2> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:47553/collection1/ shard1 [junit4] 2> 369019 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 369020 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] 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> 369023 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 369031 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 369032 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] 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":"https://127.0.0.1:47553";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 369134 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] 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> 369136 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 369138 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:47553/collection1/ and leader is https://127.0.0.1:47553/collection1/ [junit4] 2> 369138 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:47553 [junit4] 2> 369138 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 369138 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 369138 INFO (coreZkRegister-1012-thread-1-processing-n:127.0.0.1:47553_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:47553_ 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> 369139 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 369140 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:47553";, [junit4] 2> "node_name":"127.0.0.1:47553_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 369140 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:47553";, [junit4] 2> "node_name":"127.0.0.1:47553_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 369243 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] 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> 369245 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 369440 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 369441 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 369442 INFO (zkCallback-303-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5b82d4b3 name:ZooKeeperConnection Watcher:127.0.0.1:45348/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 369443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 369443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 369443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 369446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 369741 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores/collection1 [junit4] 2> 369742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001 [junit4] 2> 369743 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 369745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3bf9e8f{/,null,AVAILABLE} [junit4] 2> 369747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.ServerConnector Started ServerConnector@750aa61a{SSL-http/1.1}{127.0.0.1:33420} [junit4] 2> 369747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.e.j.s.Server Started @373264ms [junit4] 2> 369748 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:44293/hdfs__localhost_44293__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=33420, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores} [junit4] 2> 369749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 369750 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/' [junit4] 2> 369764 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/solr.xml [junit4] 2> 369782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores [junit4] 2> 369782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer New CoreContainer 1203685405 [junit4] 2> 369782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/] [junit4] 2> 369782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/lib [junit4] 2> 369783 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/lib). [junit4] 2> 369791 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] 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> 369793 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 369794 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 369795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 369795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45348/solr [junit4] 2> 369795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 369795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 369796 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 369797 INFO (zkCallback-305-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@112a80be name:ZooKeeperConnection Watcher:127.0.0.1:45348 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 369798 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 369799 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 369801 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 369803 INFO (zkCallback-306-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10cdd20e name:ZooKeeperConnection Watcher:127.0.0.1:45348/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 369803 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 369807 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 370811 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33420_ [junit4] 2> 370812 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:33420_ [junit4] 2> 370819 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 370820 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94091984345825287-127.0.0.1:33420_-n_0000000001 [junit4] 2> 370821 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94091984345825284-127.0.0.1:47553_-n_0000000000 to know if I could be the leader [junit4] 2> 370821 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 370822 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 370822 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores [junit4] 2> 370823 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 370823 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores/collection1/ [junit4] 2> 370823 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 370826 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 370826 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[910494FBD3B3A55D]) [n:127.0.0.1:33420_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 370826 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 370827 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 370827 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 370827 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 370829 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:33420";, [junit4] 2> "node_name":"127.0.0.1:33420_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 370830 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:33420";, [junit4] 2> "node_name":"127.0.0.1:33420_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 370830 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 370830 INFO (OverseerStateUpdate-94091984345825284-127.0.0.1:47553_-n_0000000000) [n:127.0.0.1:47553_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 370831 INFO (zkCallback-303-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: 2) [junit4] 2> 370832 INFO (zkCallback-306-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 370832 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 370833 INFO (zkCallback-303-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 370833 INFO (zkCallback-306-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 370840 INFO (zkCallback-301-thread-1-processing-n:127.0.0.1:47553_) [n:127.0.0.1:47553_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 371828 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 371828 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 371828 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 371829 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 371829 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 371829 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_910494FBD3B3A55D-001/shard-1-001/cores/collection1/' [junit4] 2> 371859 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 371877 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 371887 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 371899 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 371901 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 371909 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 372110 INFO (coreLoadExecutor-1029-thread-1-processing-n:127.0.0.1:33420_) [n:127.0.0.1:33420_ c:collection1 x:collecti [...truncated too long message...] ctive-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-collections-3.2.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/presto-parser-0.108.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/build/analysis/icu/lucene-analyzers-icu-6.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/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.8/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-trunk/solr/build/solr-core/test/temp/junit4-J1-20150701_014302_324.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20150701_014302_324.suites [junit4] ERROR: JVM J1 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:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: 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: 254 minutes 44 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
