Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/706/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=100649, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=100649, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:36241: Could not find collection : awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([3AA34645BBFD45BC]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:235) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:227) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:902) Build Log: [...truncated 11063 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/init-core-data-001 [junit4] 2> 2828562 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 2828562 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 2828600 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 2828607 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2828610 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2828621 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-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_38646_hdfs____.k1wl9f/webapp [junit4] 2> 2828715 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2829169 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38646 [junit4] 2> 2829245 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2829246 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2829257 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-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_35377_datanode____1viujc/webapp [junit4] 2> 2829350 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2829860 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:35377 [junit4] 2> 2829914 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2829916 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2829939 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-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_35280_datanode____2jrf1r/webapp [junit4] 2> 2830036 INFO (IPC Server handler 3 on 41685) [ ] BlockStateChange BLOCK* processReport: from storage DS-24e5ae0f-7766-4c53-b98c-9faa74480cad node DatanodeRegistration(127.0.0.1, datanodeUuid=c52ea431-2597-4c72-8007-4c90447fea10, infoPort=35377, ipcPort=39798, storageInfo=lv=-56;cid=testClusterID;nsid=697804268;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 2830037 INFO (IPC Server handler 3 on 41685) [ ] BlockStateChange BLOCK* processReport: from storage DS-8f65a95f-cb1c-44f5-9bdd-1cf7e88d0c5b node DatanodeRegistration(127.0.0.1, datanodeUuid=c52ea431-2597-4c72-8007-4c90447fea10, infoPort=35377, ipcPort=39798, storageInfo=lv=-56;cid=testClusterID;nsid=697804268;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 2830060 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2830445 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:35280 [junit4] 2> 2830507 INFO (IPC Server handler 5 on 41685) [ ] BlockStateChange BLOCK* processReport: from storage DS-e07844fd-5707-4f39-80e0-5421923cf3d7 node DatanodeRegistration(127.0.0.1, datanodeUuid=24e8ceb7-5f2b-4412-be5d-06cb5582d688, infoPort=35280, ipcPort=56709, storageInfo=lv=-56;cid=testClusterID;nsid=697804268;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 2830508 INFO (IPC Server handler 5 on 41685) [ ] BlockStateChange BLOCK* processReport: from storage DS-330470b5-7348-49f0-84c5-a9a5c6d257e2 node DatanodeRegistration(127.0.0.1, datanodeUuid=24e8ceb7-5f2b-4412-be5d-06cb5582d688, infoPort=35280, ipcPort=56709, storageInfo=lv=-56;cid=testClusterID;nsid=697804268;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 2830629 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2830629 INFO (Thread-92876) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2830629 INFO (Thread-92876) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2830729 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkTestServer start zk server on port:40180 [junit4] 2> 2830729 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2830730 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2830733 INFO (zkCallback-1437-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ae3f5bb name:ZooKeeperConnection Watcher:127.0.0.1:40180 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2830733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2830733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2830733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2830736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2830737 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2830738 INFO (zkCallback-1438-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@77593fc8 name:ZooKeeperConnection Watcher:127.0.0.1:40180/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2830738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2830738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2830738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2830740 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2830742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2830743 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2830745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2830745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2830747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2830747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2830749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2830750 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2830751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2830751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2830753 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2830753 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2830754 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2830755 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2830756 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2830756 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2830758 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2830758 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2830759 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2830759 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2830761 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2830761 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2830762 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2830762 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2831043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1 [junit4] 2> 2831044 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.Server jetty-9.2.10.v20150310 [junit4] 2> 2831046 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8a5daca{/,null,AVAILABLE} [junit4] 2> 2831046 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@296f45dc{HTTP/1.1}{127.0.0.1:45366} [junit4] 2> 2831047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.Server Started @2834373ms [junit4] 2> 2831047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:41685/hdfs__localhost_41685__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3AA34645BBFD45BC-001_tempDir-002_control_data, hostContext=/, hostPort=45366, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores} [junit4] 2> 2831047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 2831047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/' [junit4] 2> 2831059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/solr.xml [junit4] 2> 2831063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores [junit4] 2> 2831063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer New CoreContainer 1956900224 [junit4] 2> 2831064 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/] [junit4] 2> 2831064 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/lib [junit4] 2> 2831064 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/lib). [junit4] 2> 2831069 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] 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> 2831070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2831071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2831071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2831071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40180/solr [junit4] 2> 2831071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2831071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2831072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2831073 INFO (zkCallback-1440-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7935ccff name:ZooKeeperConnection Watcher:127.0.0.1:40180 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2831073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2831074 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2831075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2831076 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21889a66 name:ZooKeeperConnection Watcher:127.0.0.1:40180/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2831076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2831077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2831078 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2831080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2831081 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2831083 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2831085 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2831086 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2831087 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2831087 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45366_ [junit4] 2> 2831088 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45366_ [junit4] 2> 2831089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2831090 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2831091 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2831092 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45366_ [junit4] 2> 2831093 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2831094 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer Overseer (id=93967241068019715-127.0.0.1:45366_-n_0000000000) starting [junit4] 2> 2831095 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2831100 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2831100 INFO (OverseerCollectionProcessor-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 2831100 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2831100 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2831104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2831104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2831105 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores [junit4] 2> 2831106 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 2831106 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1/ [junit4] 2> 2831106 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 2831107 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2831107 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2831107 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 2831107 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:45366_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2831108 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2831108 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2831108 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45366";, [junit4] 2> "node_name":"127.0.0.1:45366_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 2831109 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45366";, [junit4] 2> "node_name":"127.0.0.1:45366_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2831109 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2831109 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2831110 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] 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> 2831111 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 2832108 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2832108 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2832109 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2832109 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 2832109 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2832109 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1/' [junit4] 2> 2832120 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2832123 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2832142 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2832149 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 2832150 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 2832154 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2832238 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2832243 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2832244 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2832251 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2832252 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2832254 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2832255 WARN (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2832255 WARN (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2832255 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2832256 WARN (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2832256 WARN (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2832256 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2832256 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 2832256 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:41685/solr_hdfs_home [junit4] 2> 2832256 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 2832256 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 2832257 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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@1129d628 [junit4] 2> 2832257 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:41685/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 2832271 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:41685/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 2832273 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:41685/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 2832279 WARN (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:41685/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 2832280 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:41685/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 2832291 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:41685/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 2832291 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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=1414691011, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 2832311 INFO (IPC Server handler 3 on 41685) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49754 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f65a95f-cb1c-44f5-9bdd-1cf7e88d0c5b:NORMAL:127.0.0.1:45175|RBW], ReplicaUnderConstruction[[DISK]DS-e07844fd-5707-4f39-80e0-5421923cf3d7:NORMAL:127.0.0.1:49754|RBW]]} size 0 [junit4] 2> 2832313 INFO (IPC Server handler 5 on 41685) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:45175 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-8f65a95f-cb1c-44f5-9bdd-1cf7e88d0c5b:NORMAL:127.0.0.1:45175|RBW], ReplicaUnderConstruction[[DISK]DS-e07844fd-5707-4f39-80e0-5421923cf3d7:NORMAL:127.0.0.1:49754|RBW]]} size 0 [junit4] 2> 2832317 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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@773a0578 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a2d925; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 2832317 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 2832318 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45366_ 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:41685/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 2832321 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2832321 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2832321 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2832321 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2832322 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2832322 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832322 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832322 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832322 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832324 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2832325 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2832325 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2832327 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2832329 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 2832330 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832330 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832330 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832338 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 2832339 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 2832339 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=36, maxMergedSegmentMB=33.697265625, floorSegmentMB=0.2802734375, forceMergeDeletesPctAllowed=27.51082754602515, segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8776484649668603 [junit4] 2> 2832344 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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@773a0578 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a2d925; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 2832344 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 2832345 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@79379513[collection1] main [junit4] 2> 2832345 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832345 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832346 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832346 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2832346 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2832346 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832347 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832347 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832347 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2832347 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2832348 INFO (searcherExecutor-5013-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@79379513[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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 1503475858889768960 [junit4] 2> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ 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 1503475858889768960 [junit4] 2> 2832348 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2832349 INFO (coreLoadExecutor-5012-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2832349 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:45366 collection:control_collection shard:shard1 [junit4] 2> 2832350 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ 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> 2832353 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 2832354 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2832354 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2832354 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ 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> 2832355 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45366/collection1/ [junit4] 2> 2832355 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] 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> 2832355 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2832355 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:45366/collection1/ has no replicas [junit4] 2> 2832355 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:45366/collection1/ shard1 [junit4] 2> 2832355 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2832358 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2832359 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:45366";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2832461 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] 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> 2832463 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 2832509 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:45366/collection1/ and leader is http://127.0.0.1:45366/collection1/ [junit4] 2> 2832509 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:45366 [junit4] 2> 2832509 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2832509 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2832509 INFO (coreZkRegister-5006-thread-1-processing-n:127.0.0.1:45366_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45366_ 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> 2832511 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2832511 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45366";, [junit4] 2> "node_name":"127.0.0.1:45366_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2832512 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45366";, [junit4] 2> "node_name":"127.0.0.1:45366_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2832609 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2832610 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2832611 INFO (zkCallback-1443-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7424a2ec name:ZooKeeperConnection Watcher:127.0.0.1:40180/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2832611 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2832611 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2832611 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2832614 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] 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> 2832614 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2832614 INFO (zkCallback-1443-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 2832619 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 2832619 INFO (zkCallback-1443-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 2832899 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1 [junit4] 2> 2832900 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001 [junit4] 2> 2832901 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.Server jetty-9.2.10.v20150310 [junit4] 2> 2832903 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@18a5d1a7{/,null,AVAILABLE} [junit4] 2> 2832903 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5655caa8{HTTP/1.1}{127.0.0.1:59254} [junit4] 2> 2832903 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.e.j.s.Server Started @2836229ms [junit4] 2> 2832903 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:41685/hdfs__localhost_41685__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_3AA34645BBFD45BC-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=59254, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores} [junit4] 2> 2832904 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 2832904 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/' [junit4] 2> 2832916 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/solr.xml [junit4] 2> 2832920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores [junit4] 2> 2832920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer New CoreContainer 980899170 [junit4] 2> 2832920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/] [junit4] 2> 2832920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/lib [junit4] 2> 2832920 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/lib). [junit4] 2> 2832926 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] 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> 2832927 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2832927 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2832928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2832928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40180/solr [junit4] 2> 2832928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2832928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2832929 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2832930 INFO (zkCallback-1445-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a29ab7 name:ZooKeeperConnection Watcher:127.0.0.1:40180 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2832930 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2832930 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2832931 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2832932 INFO (zkCallback-1446-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5b35cb70 name:ZooKeeperConnection Watcher:127.0.0.1:40180/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2832932 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2832939 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2833942 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59254_ [junit4] 2> 2833943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59254_ [junit4] 2> 2833945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2833947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2833947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2833948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores [junit4] 2> 2833949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 2833949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1/ [junit4] 2> 2833949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 2833950 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2833950 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2833950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 2833950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[3AA34645BBFD45BC]) [n:127.0.0.1:59254_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2833950 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 2833950 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2833951 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59254";, [junit4] 2> "node_name":"127.0.0.1:59254_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 2833951 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59254";, [junit4] 2> "node_name":"127.0.0.1:59254_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2833952 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2833952 INFO (OverseerStateUpdate-93967241068019715-127.0.0.1:45366_-n_0000000000) [n:127.0.0.1:45366_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 2834054 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2834054 INFO (zkCallback-1446-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2834054 INFO (zkCallback-1443-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2834055 INFO (zkCallback-1441-thread-1-processing-n:127.0.0.1:45366_) [n:127.0.0.1:45366_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 2834056 INFO (zkCallback-1446-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 2834056 INFO (zkCallback-1443-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 2834951 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2834951 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 2834952 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2834952 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 2834952 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2834952 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1/' [junit4] 2> 2834964 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2834975 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2834979 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2834986 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 2834987 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 2834991 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2835079 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2835086 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2835087 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2835096 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2835097 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2835099 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2835099 WARN (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2835100 WARN (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2835100 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2835100 WARN (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2835100 WARN (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:41685/solr_hdfs_home [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thread-1-processing-n:127.0.0.1:59254_) [n:127.0.0.1:59254_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest 3AA34645BBFD45BC-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 2835101 INFO (coreLoadExecutor-5023-thr [...truncated too long message...] ean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 3050072 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[3AA34645BBFD45BC]-worker) [n:127.0.0.1:59092_ c:addReplicaColl s:shard1 r:core_node5 x:addReplicaColl_shard1_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jun 09, 2015 1:08:50 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l_dv=BlockTreeOrds(blocksize=128), _version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), multiDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_i1=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=BlockTreeOrds(blocksize=128), range_facet_i_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=Lucene50(blocksize=128), range_facet_l=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=ro_RO, timezone=Asia/Kathmandu [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=65722352,total=526385152 [junit4] 2> NOTE: All tests run in this JVM: [RemoteQueryErrorTest, TestTrackingShardHandlerFactory, TestJmxMonitoredMap, ReplicationFactorTest, DistributedFacetPivotWhiteBoxTest, IndexBasedSpellCheckerTest, OverseerRolesTest, DebugComponentTest, TestRandomDVFaceting, MinimalSchemaTest, TestJoin, CurrencyFieldXmlFileTest, DistributedExpandComponentTest, HighlighterConfigTest, TestIBSimilarityFactory, SuggesterWFSTTest, TestInfoStreamLogging, CollectionsAPIAsyncDistributedZkTest, DistributedQueryComponentCustomSortTest, UniqFieldsUpdateProcessorFactoryTest, HdfsBasicDistributedZk2Test, TestDistributedSearch, LeaderElectionIntegrationTest, TestNRTOpen, CloudExitableDirectoryReaderTest, TestQuerySenderListener, ZkNodePropsTest, HdfsNNFailoverTest, TestBlobHandler, UUIDUpdateProcessorFallbackTest, TestPhraseSuggestions, DistributedSpellCheckComponentTest, HttpPartitionTest, HighlighterMaxOffsetTest, TestRemoveLastDynamicCopyField, TestCloudSchemaless, SyncSliceTest, TestSolrIndexConfig, TestClassNameShortening, TestSchemaResource, SchemaVersionSpecificBehaviorTest, TestLazyCores, TestImplicitCoreProperties, SharedFSAutoReplicaFailoverTest, TestUpdate, URLClassifyProcessorTest, TestManagedSchema, PrimitiveFieldTypeTest, TestTolerantSearch, TestSolrConfigHandlerCloud, StandardRequestHandlerTest, TestIndexingPerformance, CollectionReloadTest, TestSerializedLuceneMatchVersion, SuggesterFSTTest, RulesTest, TestBulkSchemaConcurrent, DeleteInactiveReplicaTest, ExternalFileFieldSortTest, TestReloadAndDeleteDocs, FastVectorHighlighterTest, DistributedFacetPivotSmallAdvancedTest, TestComplexPhraseQParserPlugin, TestAnalyzeInfixSuggestions, DistributedQueryComponentOptimizationTest, TestRealTimeGet, PingRequestHandlerTest, InfoHandlerTest, BasicDistributedZkTest, ChaosMonkeyNothingIsSafeTest, TestConfigOverlay, HdfsBasicDistributedZkTest, RuleEngineTest, DistributedFacetPivotSmallTest, CSVRequestHandlerTest, ShardSplitTest, SolrIndexConfigTest, TestSweetSpotSimilarityFactory, RequestLoggingTest, TestSolrDeletionPolicy1, TestDistribDocBasedVersion, TestReplicaProperties, TestRequestStatusCollectionAPI, TestSolrCloudWithKerberosAlt, VMParamsZkACLAndCredentialsProvidersTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [424/506] on J0 in 231.42s, 1 test, 1 error <<< FAILURES! [...truncated 259 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:512: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1418: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:973: There were test failures: 506 suites, 1990 tests, 1 error, 20 ignored (10 assumptions) Total time: 158 minutes 34 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]
