Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/741/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=9193, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9193, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40813/gtjt: collection already exists: awholynewstresscollection_collection1_1 at __randomizedtesting.SeedInfo.seed([ED2BC2B6C57EB777]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=49415, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=49415, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51940: Could not find collection : awholynewstresscollection_collection5_1 at __randomizedtesting.SeedInfo.seed([ED2BC2B6C57EB777]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10543 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_ED2BC2B6C57EB777-001/init-core-data-001 [junit4] 2> 545194 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 545194 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 546346 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your platform... using builtin-java classes where applicable [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 547841 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 548042 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog [junit4] 2> 548053 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 548183 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 548248 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-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_48941_hdfs____zee5uk/webapp [junit4] 2> 548788 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 549964 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48941 [junit4] 2> 551422 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 551430 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 551448 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-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_54026_datanode____qxxfq4/webapp [junit4] 2> 551705 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 552367 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54026 [junit4] 2> 552824 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 552826 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 552850 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-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_52122_datanode____h512e3/webapp [junit4] 2> 553035 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 554061 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52122 [junit4] 2> 554767 INFO (IPC Server handler 6 on 38574) [ ] BlockStateChange BLOCK* processReport: from storage DS-d3cac151-3df0-4ebb-9d43-22e888c5c55d node DatanodeRegistration(127.0.0.1, datanodeUuid=65cb6d49-d0c8-4c27-abc7-7e9c4d8a9b3c, infoPort=52122, ipcPort=39960, storageInfo=lv=-56;cid=testClusterID;nsid=1922428434;c=0), blocks: 0, hasStaleStorages: true, processing time: 6 msecs [junit4] 2> 554769 INFO (IPC Server handler 6 on 38574) [ ] BlockStateChange BLOCK* processReport: from storage DS-19856c7b-f16c-4cb6-b4ec-056cc384673f node DatanodeRegistration(127.0.0.1, datanodeUuid=65cb6d49-d0c8-4c27-abc7-7e9c4d8a9b3c, infoPort=52122, ipcPort=39960, storageInfo=lv=-56;cid=testClusterID;nsid=1922428434;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 554793 INFO (IPC Server handler 4 on 38574) [ ] BlockStateChange BLOCK* processReport: from storage DS-679d4fe3-d1a1-4f94-b1d7-0657db78183b node DatanodeRegistration(127.0.0.1, datanodeUuid=0316d60a-be5d-4c66-b0ac-431c822f6527, infoPort=54026, ipcPort=40898, storageInfo=lv=-56;cid=testClusterID;nsid=1922428434;c=0), blocks: 0, hasStaleStorages: true, processing time: 10 msecs [junit4] 2> 554802 INFO (IPC Server handler 4 on 38574) [ ] BlockStateChange BLOCK* processReport: from storage DS-ec700778-501f-4aeb-984c-7d575bc2efea node DatanodeRegistration(127.0.0.1, datanodeUuid=0316d60a-be5d-4c66-b0ac-431c822f6527, infoPort=54026, ipcPort=40898, storageInfo=lv=-56;cid=testClusterID;nsid=1922428434;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 555795 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 555796 INFO (Thread-48101) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 555796 INFO (Thread-48101) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 555896 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkTestServer start zk server on port:52435 [junit4] 2> 555897 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 555898 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 555919 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@460f8149 name:ZooKeeperConnection Watcher:127.0.0.1:52435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 555919 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 555920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 555920 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 555928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 555934 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 555954 INFO (zkCallback-87-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@412c70e7 name:ZooKeeperConnection Watcher:127.0.0.1:52435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 555954 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 555955 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 555955 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 555958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 555966 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 555988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 555996 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556001 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 556012 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556013 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 556014 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 556016 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556016 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 556021 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556021 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 556028 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556029 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 556030 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556031 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 556032 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556032 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 556034 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556034 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 556036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 556037 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556038 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 556050 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 556053 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 556061 INFO (zkCallback-88-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9689f63 name:ZooKeeperConnection Watcher:127.0.0.1:52435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 556061 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 556061 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 556062 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 556066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 556085 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 556097 INFO (zkCallback-89-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1dd634e7 name:ZooKeeperConnection Watcher:127.0.0.1:52435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 556097 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 556098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 556098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/solrconfig.xml [junit4] 2> 556099 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 556117 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/schema.xml [junit4] 2> 556117 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 556119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/enumsConfig.xml [junit4] 2> 556119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 556121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 556121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 556122 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/stopwords.txt [junit4] 2> 556123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 556124 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/protwords.txt [junit4] 2> 556124 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 556126 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/currency.xml [junit4] 2> 556126 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 556127 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/open-exchange-rates.json [junit4] 2> 556128 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 556129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 556129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 556131 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/old_synonyms.txt [junit4] 2> 556131 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 556132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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/conf2/synonyms.txt [junit4] 2> 556132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 556134 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 556134 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 556613 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/cores/collection1 [junit4] 2> 556618 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 556626 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27c0036e{/,null,AVAILABLE} [junit4] 2> 556626 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2825b105{HTTP/1.1}{127.0.0.1:46359} [junit4] 2> 556626 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.Server Started @559972ms [junit4] 2> 556626 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:38574/hdfs__localhost_38574__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_ED2BC2B6C57EB777-001_tempDir-002_control_data, hostContext=/, hostPort=46359, 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_ED2BC2B6C57EB777-001/control-001/cores} [junit4] 2> 556627 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 556627 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/' [junit4] 2> 556665 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/solr.xml [junit4] 2> 556697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/cores [junit4] 2> 556697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.CoreContainer New CoreContainer 1300362805 [junit4] 2> 556697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/] [junit4] 2> 556698 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/lib [junit4] 2> 556699 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/control-001/lib). [junit4] 2> 556721 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 556725 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 556726 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 556727 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 556727 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52435/solr [junit4] 2> 556728 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 556728 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 556729 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 556756 INFO (zkCallback-91-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e68829d name:ZooKeeperConnection Watcher:127.0.0.1:52435 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 556757 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 556757 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 556760 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 556788 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f97caf0 name:ZooKeeperConnection Watcher:127.0.0.1:52435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 556789 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 556791 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 556793 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 556796 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 556802 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 556805 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 556807 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 556834 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 556857 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 556858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46359_ [junit4] 2> 556858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:46359_ [junit4] 2> 556860 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 556863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 556869 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 556870 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94176930417475589-127.0.0.1:46359_-n_0000000000 [junit4] 2> 556870 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46359_ [junit4] 2> 556871 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 556875 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.Overseer Overseer (id=94176930417475589-127.0.0.1:46359_-n_0000000000) starting [junit4] 2> 556877 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 556885 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 556885 INFO (OverseerCollectionProcessor-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 556885 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 556886 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 556896 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 556897 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 556898 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] 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_ED2BC2B6C57EB777-001/control-001/cores [junit4] 2> 556899 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, 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_ED2BC2B6C57EB777-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_ED2BC2B6C57EB777-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 556899 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] 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_ED2BC2B6C57EB777-001/control-001/cores/collection1/ [junit4] 2> 556899 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 556900 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 556900 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 556901 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 556901 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 556901 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [n:127.0.0.1:46359_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 556901 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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:46359";, [junit4] 2> "node_name":"127.0.0.1:46359_", [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> 556902 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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:46359";, [junit4] 2> "node_name":"127.0.0.1:46359_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 556902 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 556902 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 556903 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] 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> 556907 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 556908 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 557901 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 557902 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 557902 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 557902 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 557903 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 557903 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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_ED2BC2B6C57EB777-001/control-001/cores/collection1/' [junit4] 2> 557930 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 557942 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 557950 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 557962 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 557964 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 557973 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 558161 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 558244 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 558245 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 558265 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 558268 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 558270 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 558271 WARN (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 558271 WARN (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 558271 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 558272 WARN (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 558272 WARN (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 558273 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties [junit4] 2> org.apache.solr.core.SolrResourceNotFoundException: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> at org.apache.solr.cloud.ZkSolrResourceLoader.openResource(ZkSolrResourceLoader.java:99) [junit4] 2> at org.apache.solr.core.ConfigSetProperties.readFromResourceLoader(ConfigSetProperties.java:49) [junit4] 2> at org.apache.solr.core.ConfigSetService.createConfigSetProperties(ConfigSetService.java:114) [junit4] 2> at org.apache.solr.core.ConfigSetService.getConfig(ConfigSetService.java:76) [junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:668) [junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:397) [junit4] 2> at org.apache.solr.core.CoreContainer$1.call(CoreContainer.java:388) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:156) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 558273 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 558274 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 558289 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:38574/solr_hdfs_home [junit4] 2> 558289 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 558290 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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_ED2BC2B6C57EB777-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 558290 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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@17c93c11 [junit4] 2> 558290 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 558538 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 558547 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 558558 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 558591 WARN (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:38574/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 558592 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 558618 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 558618 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:38574/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 558619 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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=44, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=83.142578125, floorSegmentMB=1.5390625, forceMergeDeletesPctAllowed=3.566220373364344, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 559193 INFO (IPC Server handler 6 on 38574) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42746 is added to blk_1073741825_1001{blockUCState=COMMITTED, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-19856c7b-f16c-4cb6-b4ec-056cc384673f:NORMAL:127.0.0.1:54936|RBW], ReplicaUnderConstruction[[DISK]DS-679d4fe3-d1a1-4f94-b1d7-0657db78183b:NORMAL:127.0.0.1:42746|RBW]]} size 71 [junit4] 2> 559199 INFO (IPC Server handler 3 on 38574) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:54936 is added to blk_1073741825_1001 size 71 [junit4] 2> 559585 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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@b6a47b2c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@788e69; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 559585 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 559592 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559593 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559594 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559594 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559596 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 559597 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 559598 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 559599 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 559604 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:46359_ 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:38574/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 559622 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559622 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559689 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559689 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559754 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 559755 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 559763 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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=1742926484, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 559917 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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@b6a47b2c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@788e69; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 559918 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 559918 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@154d5969[collection1] main [junit4] 2> 559932 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559933 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559933 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559933 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 559934 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 559934 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559934 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559935 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559935 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 559935 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 559942 INFO (searcherExecutor-461-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@154d5969[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 559955 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559955 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559955 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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> 559956 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ 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 1506830891040636928 [junit4] 2> 559956 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1 ms to seed version buckets with highest version 1506830891040636928 [junit4] 2> 559956 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 559957 INFO (coreLoadExecutor-460-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 559957 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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:46359 collection:control_collection shard:shard1 [junit4] 2> 559962 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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> 559966 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94176930417475589-core_node1-n_0000000000 [junit4] 2> 559968 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 559990 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 559991 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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> 559992 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] 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> 559995 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 559997 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 560000 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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> 560000 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46359/collection1/ [junit4] 2> 560001 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 560001 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:46359/collection1/ has no replicas [junit4] 2> 560001 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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:46359/collection1/ shard1 [junit4] 2> 560001 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 560005 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 560006 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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:46359";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 560109 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] 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> 560112 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 560161 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:46359/collection1/ and leader is http://127.0.0.1:46359/collection1/ [junit4] 2> 560161 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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:46359 [junit4] 2> 560161 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 560162 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 560162 INFO (coreZkRegister-454-thread-1-processing-n:127.0.0.1:46359_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:46359_ 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> 560163 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 560164 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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:46359";, [junit4] 2> "node_name":"127.0.0.1:46359_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 560165 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] 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:46359";, [junit4] 2> "node_name":"127.0.0.1:46359_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 560274 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] 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> 560281 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 560406 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 560408 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 560421 INFO (zkCallback-94-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5cb9cd92 name:ZooKeeperConnection Watcher:127.0.0.1:52435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 560421 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 560422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 560422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 560441 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 560442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 560442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 560442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 560446 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@32065045 name:ZooKeeperConnection Watcher:127.0.0.1:52435/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 560446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 560446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 560449 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 560454 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 4 [junit4] 2> 560455 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 560581 INFO (OverseerStateUpdate-94176930417475589-127.0.0.1:46359_-n_0000000000) [n:127.0.0.1:46359_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 560589 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] 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> 560589 INFO (zkCallback-94-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> 560592 INFO (zkCallback-94-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 560593 INFO (zkCallback-92-thread-1-processing-n:127.0.0.1:46359_) [n:127.0.0.1:46359_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 561034 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/cores/collection1 [junit4] 2> 561035 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001 [junit4] 2> 561036 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 561039 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@730fc2dc{/,null,AVAILABLE} [junit4] 2> 561039 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78dd55c8{HTTP/1.1}{127.0.0.1:51940} [junit4] 2> 561040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.e.j.s.Server Started @564385ms [junit4] 2> 561040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:38574/hdfs__localhost_38574__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_ED2BC2B6C57EB777-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=51940, 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_ED2BC2B6C57EB777-001/shard-1-001/cores} [junit4] 2> 561040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 561041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/' [junit4] 2> 561080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/solr.xml [junit4] 2> 561101 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/cores [junit4] 2> 561102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.CoreContainer New CoreContainer 1150080825 [junit4] 2> 561102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/] [junit4] 2> 561102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/lib [junit4] 2> 561102 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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_ED2BC2B6C57EB777-001/shard-1-001/lib). [junit4] 2> 561114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] 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> 561119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 561120 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 561120 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 561121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52435/solr [junit4] 2> 561121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 561121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 561121 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[ED2BC2B6C57EB777]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 561131 INFO (zkCallback-97-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d1fc40d [...truncated too long message...] rj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9197, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55554/gtjt: Could not find collection : awholynewstresscollection_collection5_1 [junit4] > at __randomizedtesting.SeedInfo.seed([ED2BC2B6C57EB777]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9194, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55554/gtjt: Could not find collection : awholynewstresscollection_collection2_1 [junit4] > at __randomizedtesting.SeedInfo.seed([ED2BC2B6C57EB777]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9192, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55554/gtjt: Could not find collection : awholynewstresscollection_collection0_1 [junit4] > at __randomizedtesting.SeedInfo.seed([ED2BC2B6C57EB777]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) [junit4] 2> 1747589 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[ED2BC2B6C57EB777]-worker) [n:127.0.0.1:55554_gtjt c:addReplicaColl s:shard2 r:core_node3 x:addReplicaColl_shard2_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=FST50, range_facet_l_dv=PostingsFormat(name=Asserting), _version_=FST50, multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=FST50, a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Asserting), range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), text=Lucene50(blocksize=128), range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=no, timezone=Africa/Malabo [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=163518824,total=530579456 [junit4] 2> NOTE: All tests run in this JVM: [UUIDUpdateProcessorFallbackTest, BinaryUpdateRequestHandlerTest, TestTrie, AnalyticsMergeStrategyTest, TestSchemaSimilarityResource, TestMaxScoreQueryParser, SuggesterTest, AutoCommitTest, TestPerFieldSimilarity, NumericFieldsTest, DOMUtilTest, TestCursorMarkWithoutUniqueKey, DirectUpdateHandlerOptimizeTest, DocExpirationUpdateProcessorFactoryTest, TestJsonRequest, QueryEqualityTest, DistributedQueryElevationComponentTest, CdcrVersionReplicationTest, BasicDistributedZk2Test, TestDocSet, TestClassNameShortening, AtomicUpdatesTest, TestCSVResponseWriter, RulesTest, RecoveryZkTest, TestImplicitCoreProperties, TestRebalanceLeaders, LeaderInitiatedRecoveryOnCommitTest, TestJettySolrRunner, PluginInfoTest, TestCryptoKeys, TestDynamicLoading, TestPseudoReturnFields, TestOverriddenPrefixQueryForCustomFieldType, SolrCoreCheckLockOnStartupTest, MultiThreadedOCPTest, TestMacros, ChangedSchemaMergeTest, TestStressReorder, TestUniqueKeyFieldResource, TestReqParamsAPI, SaslZkACLProviderTest, TestBinaryResponseWriter, PrimUtilsTest, FileBasedSpellCheckerTest, BlockDirectoryTest, TestCopyFieldCollectionResource, TestSearcherReuse, TestRequestStatusCollectionAPI, TestMissingGroups, TestFieldTypeResource, LoggingHandlerTest, SharedFSAutoReplicaFailoverTest, OverseerCollectionProcessorTest, TestZkChroot, OutputWriterTest, HardAutoCommitTest, OverseerTest, CoreMergeIndexesAdminHandlerTest, HdfsRecoverLeaseTest, SynonymTokenizerTest, TestSurroundQueryParser, TestLuceneMatchVersion, TestSQLHandler, RankQueryTest, TestSort, TestSolrQueryParserDefaultOperatorResource, TestCloudPivotFacet, TestCollationField, TestSolrJ, SpatialRPTFieldTypeTest, TestRawResponseWriter, TestInitParams, TestReload, LeaderElectionTest, TestSolrCloudWithKerberosAlt, CollectionTooManyReplicasTest, RemoteQueryErrorTest, ActionThrottleTest, TestShortCircuitedRequests, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [187/517] on J2 in 340.51s, 1 test, 1 error <<< FAILURES! [...truncated 1055 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:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 517 suites, 2032 tests, 2 errors, 20 ignored (10 assumptions) Total time: 190 minutes 13 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
