Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/18/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=64703, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=64703, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55756: collection already exists: awholynewstresscollection_collection1_3 at __randomizedtesting.SeedInfo.seed([948547760D48CBB9]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1575) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1596) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:984) Build Log: [...truncated 12353 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/init-core-data-001 [junit4] 2> 2401258 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 2401258 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 2401304 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 2401322 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2401325 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2401359 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-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_49299_hdfs____.9tmtrd/webapp [junit4] 2> 2401508 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2401727 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:49299 [junit4] 2> 2402018 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2402020 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2402034 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-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_46157_datanode____.hbtxhk/webapp [junit4] 2> 2402156 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2402471 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46157 [junit4] 2> 2402752 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 2402753 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 2402763 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-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_44790_datanode____7m0et1/webapp [junit4] 2> 2402890 INFO (IPC Server handler 5 on 34362) [ ] BlockStateChange BLOCK* processReport: from storage DS-9cc030c0-0a6a-48f6-bd3b-6dbdd3332592 node DatanodeRegistration(127.0.0.1, datanodeUuid=333580e3-52f8-47e1-ad25-0ebb76883ab7, infoPort=46157, ipcPort=50603, storageInfo=lv=-56;cid=testClusterID;nsid=920334292;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 2402890 INFO (IPC Server handler 5 on 34362) [ ] BlockStateChange BLOCK* processReport: from storage DS-7796823a-908d-42fc-811c-68afdffa8988 node DatanodeRegistration(127.0.0.1, datanodeUuid=333580e3-52f8-47e1-ad25-0ebb76883ab7, infoPort=46157, ipcPort=50603, storageInfo=lv=-56;cid=testClusterID;nsid=920334292;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 2402917 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 2403206 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44790 [junit4] 2> 2403320 INFO (IPC Server handler 7 on 34362) [ ] BlockStateChange BLOCK* processReport: from storage DS-f399cde4-01da-4d68-8169-e69f268470b8 node DatanodeRegistration(127.0.0.1, datanodeUuid=da4df41d-5e52-4bb4-bbdb-9170ea2681f9, infoPort=44790, ipcPort=59889, storageInfo=lv=-56;cid=testClusterID;nsid=920334292;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 2403321 INFO (IPC Server handler 7 on 34362) [ ] BlockStateChange BLOCK* processReport: from storage DS-4a44f45a-98d3-4083-9c1a-fe415d9d84f8 node DatanodeRegistration(127.0.0.1, datanodeUuid=da4df41d-5e52-4bb4-bbdb-9170ea2681f9, infoPort=44790, ipcPort=59889, storageInfo=lv=-56;cid=testClusterID;nsid=920334292;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 2403372 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2403373 INFO (Thread-56431) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2403373 INFO (Thread-56431) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2403473 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkTestServer start zk server on port:47795 [junit4] 2> 2403474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403475 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403478 INFO (zkCallback-1716-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@759160e8 name:ZooKeeperConnection Watcher:127.0.0.1:47795 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403478 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2403488 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403490 INFO (zkCallback-1717-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67324eac name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2403492 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2403493 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2403495 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2403497 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 2403497 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2403499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2403500 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2403502 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2403503 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2403505 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 2403505 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2403506 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 2403506 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2403508 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 2403508 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2403509 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 2403509 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2403510 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 2403510 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2403511 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2403511 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2403512 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 2403512 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2403514 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 2403514 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2403517 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403518 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403519 INFO (zkCallback-1718-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b47d0b8 name:ZooKeeperConnection Watcher:127.0.0.1:47795 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2403524 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403525 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403525 INFO (zkCallback-1719-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1176639a name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403526 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403526 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403526 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 2403526 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 2403528 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 2403528 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 2403536 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 2403537 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 2403539 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2403539 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2403540 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 2403540 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 2403541 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 2403542 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 2403543 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 2403543 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 2403544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 2403544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 2403547 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2403547 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2403549 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 2403549 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 2403551 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 2403552 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 2403553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 2403553 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 2403655 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores/collection1 [junit4] 2> 2403656 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 2403658 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13c44201{/,null,AVAILABLE} [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5225fa69{HTTP/1.1}{127.0.0.1:53085} [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.Server Started @2406145ms [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=53085, solr.data.dir=hdfs://localhost:34362/hdfs__localhost_34362__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.5_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001_tempDir-002_control_data, hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores} [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001' [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2403659 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2403660 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403661 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403662 INFO (zkCallback-1720-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4de1f0ae name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403662 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403662 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403663 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2403663 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/solr.xml [junit4] 2> 2403674 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores [junit4] 2> 2403674 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer New CoreContainer 1764346230 [junit4] 2> 2403674 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001] [junit4] 2> 2403674 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/lib [junit4] 2> 2403674 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] 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> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47795/solr [junit4] 2> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2403675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2403676 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403677 INFO (zkCallback-1723-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1211b385 name:ZooKeeperConnection Watcher:127.0.0.1:47795 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2403678 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@7758bc15 [junit4] 2> 2403679 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2403680 INFO (zkCallback-1724-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68ef2dff name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2403680 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2403681 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2403683 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2403686 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2403688 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2403690 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2403692 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2403694 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2403695 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2403696 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2403697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53085_ [junit4] 2> 2403697 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:53085_ [junit4] 2> 2403699 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2403700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2403701 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2403701 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96119382391193606-127.0.0.1:53085_-n_0000000000 [junit4] 2> 2403702 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:53085_ [junit4] 2> 2403702 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2403703 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.Overseer Overseer (id=96119382391193606-127.0.0.1:53085_-n_0000000000) starting [junit4] 2> 2403706 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2403711 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2403711 INFO (OverseerCollectionConfigSetProcessor-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2403712 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2403712 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2403731 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2403731 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2403732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores [junit4] 2> 2403732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, schema=schema.xml, loadOnStartup=true, shard=, collection=control_collection, dataDir=data/, transient=false, name=collection1, config=solrconfig.xml, coreNodeName=} [junit4] 2> 2403732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores/collection1 [junit4] 2> 2403732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2403733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0 [junit4] 2> 2403733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:53085_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2403733 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2403733 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2403734 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 2403734 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 2403735 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:53085";, [junit4] 2> "node_name":"127.0.0.1:53085_", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down"} current state version: 0 [junit4] 2> 2403735 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 2403735 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:53085";, [junit4] 2> "node_name":"127.0.0.1:53085_", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down"} [junit4] 2> 2403736 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2403736 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2403736 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2403737 INFO (zkCallback-1724-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] 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> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2404739 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores/collection1' [junit4] 2> 2404740 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2404740 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2404741 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2404755 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2404772 WARN (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 2404774 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.2 [junit4] 2> 2404827 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2404833 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2405219 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2405229 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2405232 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2405239 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2405245 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2405247 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2405250 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0 [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:34362/solr_hdfs_home [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/control-001/cores/collection1], dataDir=[hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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@4f5347b4 [junit4] 2> 2405255 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 2405267 WARN (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 2410271 WARN (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 2415389 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 2415390 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 2415397 WARN (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 2415398 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 2415413 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 2415413 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=15, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27010901662516473] [junit4] 2> 2415444 INFO (IPC Server handler 4 on 34362) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:40899 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-4a44f45a-98d3-4083-9c1a-fe415d9d84f8:NORMAL:127.0.0.1:34210|RBW], ReplicaUnderConstruction[[DISK]DS-9cc030c0-0a6a-48f6-bd3b-6dbdd3332592:NORMAL:127.0.0.1:40899|FINALIZED]]} size 0 [junit4] 2> 2415446 INFO (IPC Server handler 3 on 34362) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34210 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-9cc030c0-0a6a-48f6-bd3b-6dbdd3332592:NORMAL:127.0.0.1:40899|FINALIZED], ReplicaUnderConstruction[[DISK]DS-f399cde4-01da-4d68-8169-e69f268470b8:NORMAL:127.0.0.1:34210|FINALIZED]]} size 0 [junit4] 2> 2415453 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@236ce138; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 2415453 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2415454 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:53085_ 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:34362/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415457 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415458 WARN (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 2415460 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2415461 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2415462 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2415463 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2415468 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /dump,/get,/admin/file,/update/json/docs,/admin/mbeans,/config,/replication,/update/csv,/admin/threads,/schema,/admin/ping,/admin/system,/admin/plugins,/admin/properties,standard,/update,/admin/segments,/admin/luke,/admin/logging,/update/json [junit4] 2> 2415471 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415472 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415472 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 2415472 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 2415482 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2415482 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2415485 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=47, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 2415497 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:34362/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@236ce138; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 2415497 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2415497 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@28dcd090[collection1] main [junit4] 2> 2415497 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415499 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415499 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415499 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2415499 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2415499 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415500 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415500 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415500 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2415501 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2415508 INFO (searcherExecutor-5184-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@28dcd090[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2415509 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415509 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 2415509 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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> 2415509 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ 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 1537910130982518784 [junit4] 2> 2415511 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1537910130982518784 [junit4] 2> 2415511 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2415512 INFO (coreLoadExecutor-5183-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2415512 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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:53085 collection:control_collection shard:shard1 [junit4] 2> 2415513 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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> 2415516 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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/96119382391193606-core_node1-n_0000000000 [junit4] 2> 2415517 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 2415518 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2415518 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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> 2415518 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:53085/collection1/ [junit4] 2> 2415518 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2415518 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:53085/collection1/ has no replicas [junit4] 2> 2415519 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] 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> 2415521 INFO (zkCallback-1724-thread-3-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] 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> 2415522 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2415523 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/96119382391193606-core_node1-n_0000000000 [junit4] 2> 2415524 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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:53085/collection1/ shard1 [junit4] 2> 2415525 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] 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:53085";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 2415627 INFO (zkCallback-1724-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] 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> 2415677 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:53085/collection1/ and leader is http://127.0.0.1:53085/collection1/ [junit4] 2> 2415677 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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:53085 [junit4] 2> 2415677 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2415677 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2415677 INFO (coreZkRegister-5177-thread-1-processing-s:shard1 c:control_collection r:core_node1 n:127.0.0.1:53085_ x:collection1) [n:127.0.0.1:53085_ 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> 2415678 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:53085";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:53085_", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active"} current state version: 3 [junit4] 2> 2415679 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:53085";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:53085_", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active"} [junit4] 2> 2415737 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2415738 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2415739 INFO (zkCallback-1726-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ae64f92 name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2415739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2415739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2415739 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2415741 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2415741 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 2415741 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2415742 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2415743 INFO (zkCallback-1727-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@40778a13 name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2415743 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2415744 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2415746 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] 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: 3 [junit4] 2> 2415746 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2415751 INFO (zkCallback-1726-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> 2415751 INFO (zkCallback-1724-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] 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> 2415859 INFO (OverseerStateUpdate-96119382391193606-127.0.0.1:53085_-n_0000000000) [n:127.0.0.1:53085_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 2415946 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/cores/collection1 [junit4] 2> 2415946 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001 [junit4] 2> 2415967 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 2415987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b8f1199{/,null,AVAILABLE} [junit4] 2> 2415987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1dcc7a90{HTTP/1.1}{127.0.0.1:36899} [junit4] 2> 2415987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.e.j.s.Server Started @2418473ms [junit4] 2> 2415987 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostContext=/, solr.data.dir=hdfs://localhost:34362/hdfs__localhost_34362__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.5_solr_build_solr-core_test_J0_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001_tempDir-002_jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/cores, hostPort=36899} [junit4] 2> 2415988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 2415988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001' [junit4] 2> 2415988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2415988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2415988 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2416007 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2416011 INFO (zkCallback-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b842721 name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2416011 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2416011 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2416019 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2416019 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/solr.xml [junit4] 2> 2416040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/cores [junit4] 2> 2416047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer New CoreContainer 29841294 [junit4] 2> 2416047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001] [junit4] 2> 2416047 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_948547760D48CBB9-001/shard-1-001/lib [junit4] 2> 2416048 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] 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> 2416057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2416063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2416063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2416063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47795/solr [junit4] 2> 2416063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2416063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2416066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2416072 INFO (zkCallback-1731-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b491600 name:ZooKeeperConnection Watcher:127.0.0.1:47795 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2416072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2416072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2416073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@6e42a83a [junit4] 2> 2416074 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2416076 INFO (zkCallback-1732-thread-1-processing-n:127.0.0.1:36899_) [n:127.0.0.1:36899_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44bb4dbd name:ZooKeeperConnection Watcher:127.0.0.1:47795/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2416076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2416083 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2417089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36899_ [junit4] 2> 2417089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36899_ [junit4] 2> 2417090 INFO (zkCallback-1732-thread-1-processing-n:127.0.0.1:36899_) [n:127.0.0.1:36899_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2417091 INFO (zkCallback-1724-thread-1-processing-n:127.0.0.1:53085_) [n:127.0.0.1:53085_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 2417091 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2417092 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[948547760D48CBB9]) [n:127.0.0.1:36899_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96119 [...truncated too long message...] ) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:52) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 54 more [junit4] 2> 3052955 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[948547760D48CBB9]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jun 23, 2016 7:13:50 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {a_i1=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false), id=FSTOrd50, range_facet_l_dv=FSTOrd50, range_facet_l=PostingsFormat(name=Memory doPackFST= false), _version_=BlockTreeOrds(blocksize=128), intDefault=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=PostingsFormat(name=Memory doPackFST= false), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), other_tl1=BlockTreeOrds(blocksize=128), a_t=PostingsFormat(name=Memory doPackFST= false)}, docValues:{timestamp=DocValuesFormat(name=Memory), range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Memory)}, sim=DefaultSimilarity, locale=sr, timezone=ART [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=154701616,total=499122176 [junit4] 2> NOTE: All tests run in this JVM: [ZkNodePropsTest, TestObjectReleaseTracker, TestLazyCores, StandardRequestHandlerTest, BasicZkTest, CursorMarkTest, DeleteInactiveReplicaTest, TestMinMaxOnMultiValuedField, XsltUpdateRequestHandlerTest, HdfsRecoveryZkTest, SyncSliceTest, TestShortCircuitedRequests, MigrateRouteKeyTest, AlternateDirectoryTest, SortSpecParsingTest, TestInitParams, PolyFieldTest, TestFunctionQuery, TestHdfsUpdateLog, TriLevelCompositeIdRoutingTest, DistributedSuggestComponentTest, AnalysisAfterCoreReloadTest, DefaultValueUpdateProcessorTest, TestJsonRequest, TestReplicaProperties, DistributedFacetPivotWhiteBoxTest, HdfsLockFactoryTest, EchoParamsTest, TestCSVLoader, DisMaxRequestHandlerTest, TestComponentsName, TestReplicationHandler, TestSolr4Spatial2, OverriddenZkACLAndCredentialsProvidersTest, TestUseDocValuesAsStored2, RemoteQueryErrorTest, CachingDirectoryFactoryTest, CheckHdfsIndexTest, BasicDistributedZkTest, IndexSchemaRuntimeFieldTest, DocValuesMissingTest, TestConfigSetsAPIExclusivity, TestCloudPivotFacet, TestRealTimeGet, TestOmitPositions, PeerSyncTest, TestDynamicLoading, ConnectionManagerTest, InfoHandlerTest, SynonymTokenizerTest, SuggestComponentContextFilterQueryTest, TestDistributedSearch, BitVectorTest, TestJoin, JSONWriterTest, TestExpandComponent, TestPhraseSuggestions, DistributedDebugComponentTest, TestChildDocTransformer, TestMiniSolrCloudClusterBase, TestFieldCollectionResource, TestConfigSetImmutable, CoreAdminCreateDiscoverTest, TestNamedUpdateProcessors, TestSchemaResource, ConnectionReuseTest, DocumentAnalysisRequestHandlerTest, TestTrieFacet, LeaderElectionIntegrationTest, AsyncCallRequestStatusResponseTest, TestAuthenticationFramework, DistributedQueryElevationComponentTest, PingRequestHandlerTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestRangeQuery, CursorPagingTest, CollectionStateFormat2Test, TestAnalyzedSuggestions, DistributedSpellCheckComponentTest, TestClassicSimilarityFactory, URLClassifyProcessorTest, RequiredFieldsTest, TestMaxScoreQueryParser, TestCollapseQParserPlugin, BlockDirectoryTest, TestIBSimilarityFactory, AtomicUpdatesTest, TestReRankQParserPlugin, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [412/573 (1!)] on J0 in 661.31s, 1 test, 1 error <<< FAILURES! [...truncated 543 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 573 suites (7 ignored), 2319 tests, 1 error, 34 ignored (18 assumptions) [seed: 948547760D48CBB9] Total time: 209 minutes 25 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
