Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/4/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=50030, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=50030, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:33328: Could not find collection : awholynewstresscollection_collection3_0 at __randomizedtesting.SeedInfo.seed([EBCA3C31B1B05975]: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:1085) 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 10839 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/init-core-data-001 [junit4] 2> 486562 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 486563 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 487229 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-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> 487942 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 488144 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog [junit4] 2> 488154 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 488238 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 488288 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-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_54975_hdfs____yr2vlu/webapp [junit4] 2> 488511 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 489661 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54975 [junit4] 2> 490503 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 490511 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 490525 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-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_51663_datanode____.u44c3m/webapp [junit4] 2> 490665 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 491129 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51663 [junit4] 2> 491294 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 491297 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 491323 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-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_46252_datanode____vv5cz8/webapp [junit4] 2> 491494 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 492587 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EBCA3C31B1B05975]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46252 [junit4] 2> 492761 INFO (IPC Server handler 1 on 39708) [ ] BlockStateChange BLOCK* processReport: from storage DS-0ae9146a-c7ef-476b-afeb-b5f7da7ccc57 node DatanodeRegistration(127.0.0.1, datanodeUuid=20f1d056-2d82-42bb-8b65-4df4e8fcbd5b, infoPort=51663, ipcPort=42657, storageInfo=lv=-56;cid=testClusterID;nsid=80442829;c=0), blocks: 0, hasStaleStorages: true, processing time: 17 msecs [junit4] 2> 492765 INFO (IPC Server handler 1 on 39708) [ ] BlockStateChange BLOCK* processReport: from storage DS-ccb97229-5b1a-4520-aefd-15350771b969 node DatanodeRegistration(127.0.0.1, datanodeUuid=20f1d056-2d82-42bb-8b65-4df4e8fcbd5b, infoPort=51663, ipcPort=42657, storageInfo=lv=-56;cid=testClusterID;nsid=80442829;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 492884 INFO (IPC Server handler 6 on 39708) [ ] BlockStateChange BLOCK* processReport: from storage DS-e26de320-b92c-4d07-bb05-f35143c8e28a node DatanodeRegistration(127.0.0.1, datanodeUuid=15b2b694-a186-4876-ac92-4446b5346647, infoPort=46252, ipcPort=53027, storageInfo=lv=-56;cid=testClusterID;nsid=80442829;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 492886 INFO (IPC Server handler 6 on 39708) [ ] BlockStateChange BLOCK* processReport: from storage DS-a12b91a8-317e-4257-b37c-c31274f4fdc9 node DatanodeRegistration(127.0.0.1, datanodeUuid=15b2b694-a186-4876-ac92-4446b5346647, infoPort=46252, ipcPort=53027, storageInfo=lv=-56;cid=testClusterID;nsid=80442829;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 493564 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 493565 INFO (Thread-48092) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 493566 INFO (Thread-48092) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 493665 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkTestServer start zk server on port:59280 [junit4] 2> 493665 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493666 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493670 INFO (zkCallback-88-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29b8d28b name:ZooKeeperConnection Watcher:127.0.0.1:59280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493671 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493671 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493672 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 493675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493682 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493684 INFO (zkCallback-89-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@892a6b5 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493685 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493686 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493686 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 493690 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 493692 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 493694 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 493697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 493698 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 493701 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 493702 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 493705 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 493706 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 493709 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 493710 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 493715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 493715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 493718 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 493718 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 493721 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 493722 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 493725 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 493726 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 493728 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 493729 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 493731 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 493732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 493734 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 493734 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 493738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493741 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493741 INFO (zkCallback-90-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1f143798 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493744 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493754 INFO (zkCallback-91-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c5288b1 name:ZooKeeperConnection Watcher:127.0.0.1:59280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493754 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493754 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493754 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 493758 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493759 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493760 INFO (zkCallback-92-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@485fce9a name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493760 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493761 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493761 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 493761 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 493766 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 493766 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 493775 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 493775 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 493777 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 493777 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 493780 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 493780 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 493782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 493782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 493785 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 493785 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 493787 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 493787 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 493789 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 493789 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 493792 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 493792 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 493794 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 493794 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 493797 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 493797 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 494143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1 [junit4] 2> 494161 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 494163 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30e6f8bf{/,null,AVAILABLE} [junit4] 2> 494240 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3e18dfbf{SSL-http/1.1}{127.0.0.1:54312} [junit4] 2> 494240 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.Server Started @497329ms [junit4] 2> 494241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:39708/hdfs__localhost_39708__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001_tempDir-002_control_data, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores, hostContext=/, hostPort=54312} [junit4] 2> 494241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@5472fe25 [junit4] 2> 494241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/' [junit4] 2> 494257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 494257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 494259 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c199516 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 494259 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 494260 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 494262 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 494262 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/solr.xml [junit4] 2> 494285 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores [junit4] 2> 494286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer New CoreContainer 364006059 [junit4] 2> 494286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/] [junit4] 2> 494286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/lib [junit4] 2> 494286 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/lib). [junit4] 2> 494312 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] 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> 494316 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 494319 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 494319 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 494319 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59280/solr [junit4] 2> 494319 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 494319 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 494320 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 494322 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d43069b name:ZooKeeperConnection Watcher:127.0.0.1:59280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 494323 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 494323 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 494330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 494332 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33f49392 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 494333 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 494335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 494337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 494340 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 494343 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 494346 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 494349 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 494352 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 494354 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 494356 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 494358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54312_ [junit4] 2> 494359 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:54312_ [junit4] 2> 494363 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 494370 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 494372 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 494372 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94335547600666631-127.0.0.1:54312_-n_0000000000 [junit4] 2> 494373 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54312_ [junit4] 2> 494374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 494376 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.Overseer Overseer (id=94335547600666631-127.0.0.1:54312_-n_0000000000) starting [junit4] 2> 494378 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 494387 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 494388 INFO (OverseerCollectionProcessor-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 494388 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 494389 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 494411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 494412 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 494413 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores [junit4] 2> 494414 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.SolrCore Created CoreDescriptor: {shard=, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1, transient=false, collection=control_collection, loadOnStartup=true, coreNodeName=, schema=schema.xml, name=collection1, config=solrconfig.xml, configSetProperties=configsetprops.json, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1/, dataDir=data/} [junit4] 2> 494414 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1/ [junit4] 2> 494414 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 494416 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0 [junit4] 2> 494416 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:54312_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 494416 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 494416 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 494417 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 494417 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 494419 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:54312_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:54312";, [junit4] 2> "numShards":"1"} current state version: 0 [junit4] 2> 494420 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:54312_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"https://127.0.0.1:54312";, [junit4] 2> "numShards":"1"} [junit4] 2> 494420 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 494420 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 494422 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] 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> 494425 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 495418 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 495418 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 495419 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 495419 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 495420 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 495420 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1/' [junit4] 2> 495439 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 495447 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 495463 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0 [junit4] 2> 495494 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 495495 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 495525 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 495812 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 495823 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 495827 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 495843 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 495847 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 495851 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 495853 WARN (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 495854 WARN (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 495854 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 495856 WARN (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 495856 WARN (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 495857 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0 [junit4] 2> 495857 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 495857 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 495867 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:39708/solr_hdfs_home [junit4] 2> 495868 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 495868 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 495868 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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@34e32c81 [junit4] 2> 495868 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 495948 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 495948 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 495953 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 495984 WARN (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:39708/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 495984 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 496004 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 496005 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:39708/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 496006 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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@267d3c8d [junit4] 2> 496303 INFO (IPC Server handler 4 on 39708) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:46081 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-ccb97229-5b1a-4520-aefd-15350771b969:NORMAL:127.0.0.1:46081|RBW], ReplicaUnderConstruction[[DISK]DS-e26de320-b92c-4d07-bb05-f35143c8e28a:NORMAL:127.0.0.1:37149|RBW]]} size 71 [junit4] 2> 496305 INFO (IPC Server handler 3 on 39708) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:37149 is added to blk_1073741825_1001 size 71 [junit4] 2> 496708 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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@b6c634a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc89f34; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 496708 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 496712 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:54312_ 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:39708/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 496716 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 496716 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 496717 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 496717 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 496717 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 496721 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496721 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496722 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496722 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496727 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 496730 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 496731 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 496732 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 496755 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/logging,/admin/ping,/admin/luke,/update/csv,/admin/plugins,/admin/mbeans,/update/json/docs,/admin/file,/config,/admin/segments,/schema,/admin/system,/update/json,/admin/properties,/get,/admin/threads,/replication,/update,standard [junit4] 2> 496757 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496767 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496768 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496815 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 496815 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 496818 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2023868023, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 496926 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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@b6c634a2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6fc89f34; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 496926 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 496926 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5cc39b3e[collection1] main [junit4] 2> 496926 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496927 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496927 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496928 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 496928 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 496928 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496928 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496929 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496929 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 496929 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 496929 INFO (searcherExecutor-598-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5cc39b3e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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 1509368765143318528 [junit4] 2> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ 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 1509368765143318528 [junit4] 2> 496937 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 496938 INFO (coreLoadExecutor-597-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 496938 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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:54312 collection:control_collection shard:shard1 [junit4] 2> 496939 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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> 496943 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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/94335547600666631-core_node1-n_0000000000 [junit4] 2> 496945 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 496946 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 496947 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 496947 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] 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> 496948 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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> 496949 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:54312/collection1/ [junit4] 2> 496949 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 496949 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:54312/collection1/ has no replicas [junit4] 2> 496949 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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:54312/collection1/ shard1 [junit4] 2> 496949 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 496949 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] 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> 496951 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 496957 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 496958 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] 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:54312";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 497061 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] 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> 497063 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 497108 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:54312/collection1/ and leader is https://127.0.0.1:54312/collection1/ [junit4] 2> 497108 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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:54312 [junit4] 2> 497108 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 497108 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 497109 INFO (coreZkRegister-591-thread-1-processing-x:collection1 n:127.0.0.1:54312_ s:shard1 r:core_node1 c:control_collection) [n:127.0.0.1:54312_ 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> 497111 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 497112 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:54312_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "base_url":"https://127.0.0.1:54312";, [junit4] 2> "numShards":"1"} current state version: 3 [junit4] 2> 497113 INFO (OverseerStateUpdate-94335547600666631-127.0.0.1:54312_-n_0000000000) [n:127.0.0.1:54312_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:54312_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "base_url":"https://127.0.0.1:54312";, [junit4] 2> "numShards":"1"} [junit4] 2> 497216 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] 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> 497219 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:54312_) [n:127.0.0.1:54312_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 497422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 497423 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 497424 INFO (zkCallback-98-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61fc5d83 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 497424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 497425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 497425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 497428 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 497722 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores/collection1 [junit4] 2> 497723 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001 [junit4] 2> 497725 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 497731 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@e404e09{/,null,AVAILABLE} [junit4] 2> 497738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7c23df55{SSL-http/1.1}{127.0.0.1:38053} [junit4] 2> 497738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.e.j.s.Server Started @500827ms [junit4] 2> 497739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=38053, solrconfig=solrconfig.xml, solr.data.dir=hdfs://localhost:39708/hdfs__localhost_39708__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001_tempDir-002_jetty1, hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores} [junit4] 2> 497739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@5472fe25 [junit4] 2> 497739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/' [junit4] 2> 497773 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 497786 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 497795 INFO (zkCallback-99-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a204f32 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 497795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 497795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 497811 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 497812 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/solr.xml [junit4] 2> 497840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores [junit4] 2> 497840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer New CoreContainer 127436032 [junit4] 2> 497840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/] [junit4] 2> 497840 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/lib [junit4] 2> 497841 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/lib). [junit4] 2> 497857 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] 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> 497862 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 497865 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 497865 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 497865 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59280/solr [junit4] 2> 497866 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 497866 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 497875 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 497890 INFO (zkCallback-101-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3640ef98 name:ZooKeeperConnection Watcher:127.0.0.1:59280 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 497894 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 497894 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 497914 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 497938 INFO (zkCallback-102-thread-1-processing-n:127.0.0.1:38053_) [n:127.0.0.1:38053_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ffabe22 name:ZooKeeperConnection Watcher:127.0.0.1:59280/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 497938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 497951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 498956 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38053_ [junit4] 2> 498957 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:38053_ [junit4] 2> 498960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 498961 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94335547600666635-127.0.0.1:38053_-n_0000000001 [junit4] 2> 498962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94335547600666631-127.0.0.1:54312_-n_0000000000 to know if I could be the leader [junit4] 2> 499053 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 499053 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 499055 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores [junit4] 2> 499057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores/collection1/, config=solrconfig.xml, shard=, collection=collection1, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores/collection1, name=collection1, transient=false, coreNodeName=, loadOnStartup=true, configSetProperties=configsetprops.json, schema=schema.xml, dataDir=data/} [junit4] 2> 499057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_EBCA3C31B1B05975-001/shard-1-001/cores/collection1/ [junit4] 2> 499058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]) [n:127.0.0.1:38053_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 499065 INFO (coreLoadExecutor-608-thread-1-processing-n:127.0.0.1:38053_) [n:127.0.0.1:38053_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 499065 INFO (coreLoadExecutor-608-thread-1-processing-n:127.0.0.1:38053_) [n:127.0.0.1:38053_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 499066 INFO (coreLoadExecutor-608-thread-1-processing-n:127.0.0.1:38053_) [n:127.0.0.1:38053_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 499066 INFO (zkCallback-96-thread-1-processing-n: [...truncated too long message...] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [junit4] 2> at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] 2> at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] 2> ... 7 more [junit4] 2> Caused by: java.net.SocketTimeoutException: Read timed out [junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method) [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:152) [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] 2> at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] 2> at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] 2> at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] 2> at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] 2> at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:465) [junit4] 2> ... 11 more [junit4] 2> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l_dv=FST50, multiDefault=PostingsFormat(name=Direct), a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=FST50, range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{timestamp=DocValuesFormat(name=Lucene50), range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ar_BH, timezone=Indian/Comoro [junit4] 2> 2628902 INFO (qtp1359625835-12798) [n:127.0.0.1:39114_tl_%2Ft ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params numShards=4&stateFormat=1&maxShardsPerNode=7&replicationFactor=4&name=awholynewstresscollection_collection5_0&version=2&action=CREATE&wt=javabin&collection.configName=conf1 [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_72 (64-bit)/cpus=4,threads=352,free=53947584,total=470810624 [junit4] 2> NOTE: All tests run in this JVM: [ResourceLoaderTest, TestIndexSearcher, ZkControllerTest, DocumentAnalysisRequestHandlerTest, DocValuesMultiTest, SimpleMLTQParserTest, TestHashQParserPlugin, CurrencyFieldOpenExchangeTest, TestDistributedMissingSort, TestSolrCloudWithKerberosAlt, SolrTestCaseJ4Test, TestSortByMinMaxFunction, TestManagedSchemaDynamicFieldResource, FileUtilsTest, TestHighlightDedupGrouping, TestAnalyzeInfixSuggestions, TestSuggestSpellingConverter, AsyncCallRequestStatusResponseTest, TestMiniSolrCloudCluster, TestPHPSerializedResponseWriter, StatelessScriptUpdateProcessorFactoryTest, TestRuleBasedAuthorizationPlugin, SecurityConfHandlerTest, HdfsWriteToMultipleCollectionsTest, DirectSolrConnectionTest, ShardSplitTest, TestSolrDynamicMBean, AliasIntegrationTest, SpellCheckComponentTest, NoCacheHeaderTest, SystemInfoHandlerTest, TestShardHandlerFactory, TestAtomicUpdateErrorCases, LukeRequestHandlerTest, DistributedTermsComponentTest, TestFieldTypeResource, SpatialRPTFieldTypeTest, TestConfigSetProperties, ReturnFieldsTest, TestLRUStatsCache, TestSerializedLuceneMatchVersion, TestJsonFacets, AnalysisAfterCoreReloadTest, EchoParamsTest, TestCloudSchemaless, TestCoreDiscovery, OverseerRolesTest, TestCrossCoreJoin, TestManagedSchemaFieldTypeResource, DistributedFacetPivotSmallTest, TestRandomMergePolicy, SpellingQueryConverterTest, CopyFieldTest, TestSolrCoreProperties, TestClassNameShortening, DistributedMLTComponentTest, DistributedFacetPivotLongTailTest, CacheHeaderTest, TestFiltering, TestSolrDeletionPolicy2, TestDistributedStatsComponentCardinality, IndexSchemaRuntimeFieldTest, BasicAuthIntegrationTest, BasicFunctionalityTest, TestSmileRequest, SpatialFilterTest, TestInitParams, TestJettySolrRunner, RequestLoggingTest, CursorMarkTest, TestInitQParser, CursorPagingTest, TestReRankQParserPlugin, TestOverriddenPrefixQueryForCustomFieldType, CollectionStateFormat2Test, TestSha256AuthenticationProvider, TestManagedResourceStorage, ConvertedLegacyTest, HdfsLockFactoryTest, TestSimpleQParserPlugin, TestLMJelinekMercerSimilarityFactory, TestFastWriter, OpenCloseCoreStressTest, ChaosMonkeySafeLeaderTest, CoreAdminHandlerTest, SmileWriterTest, CollectionsAPIDistributedZkTest] [junit4] 2> 2615014 ERROR (SyncThread:0) [n:127.0.0.1:58006_tl_%2Ft c:awholynewcollection_3 s:shard5 r:core_node16 x:awholynewcollection_3_shard5_replica2] o.a.z.s.NIOServerCnxn Unexpected Exception: [junit4] 2> java.nio.channels.CancelledKeyException [junit4] 2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73) [junit4] 2> at sun.nio.ch.SelectionKeyImpl.interestOps(SelectionKeyImpl.java:77) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.sendBuffer(NIOServerCnxn.java:151) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.sendResponse(NIOServerCnxn.java:1081) [junit4] 2> at org.apache.zookeeper.server.FinalRequestProcessor.processRequest(FinalRequestProcessor.java:170) [junit4] 2> at org.apache.zookeeper.server.SyncRequestProcessor.run(SyncRequestProcessor.java:169) [junit4] ERROR 0.00s J1 | CollectionsAPIDistributedZkTest (suite) <<< [junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space [junit4] Completed [531/531] on J1 in 24646.93s, 1 test, 1 error <<< FAILURES! [...truncated 1 lines...] [junit4] JVM J1: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J1-20150813_020205_168.sysout [junit4] >>> JVM J1: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: Java heap space [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid21686.hprof ... [junit4] Heap dump file created [614341380 bytes in 7.538 secs] [junit4] <<< JVM J1: EOF ---- [junit4] JVM J1: stderr was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J1-20150813_020205_168.syserr [junit4] >>> JVM J1: stderr (verbatim) ---- [junit4] [junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "collection3" [junit4] [junit4] Exception: java.lang.OutOfMemoryError thrown from the UncaughtExceptionHandler in thread "TEST-CollectionsAPIDistributedZkTest.test-seed#[EBCA3C31B1B05975]-SendThread(127.0.0.1:51130)" [junit4] <<< JVM J1: EOF ---- [...truncated 11 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 531 suites, 2104 tests, 1 suite-level error, 1 error, 26 ignored (14 assumptions) Total time: 563 minutes 57 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]
