Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/19/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=130784, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=130784, name=collection3, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:58658/mn/l: collection already exists: awholynewstresscollection_collection3_1 at __randomizedtesting.SeedInfo.seed([CB8363A083754520]: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 12550 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/init-core-data-001 [junit4] 2> 3795446 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 3795446 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /mn/l [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 3795480 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 3795486 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3795488 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3795500 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-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_49962_hdfs____154wca/webapp [junit4] 2> 3795599 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3795806 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:49962 [junit4] 2> 3795865 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3795866 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3795874 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-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_47269_datanode____.iwljpj/webapp [junit4] 2> 3795983 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3796275 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47269 [junit4] 2> 3796295 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 3796296 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 3796308 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-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_52362_datanode____xk5p3t/webapp [junit4] 2> 3796359 INFO (IPC Server handler 3 on 56311) [ ] BlockStateChange BLOCK* processReport: from storage DS-b7012733-5ec2-47b7-a19e-2026b53c2f84 node DatanodeRegistration(127.0.0.1, datanodeUuid=5de52943-ebf7-44b2-9eaf-75d5100a6c45, infoPort=47269, ipcPort=34281, storageInfo=lv=-56;cid=testClusterID;nsid=673753889;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3796360 INFO (IPC Server handler 3 on 56311) [ ] BlockStateChange BLOCK* processReport: from storage DS-4bc2004d-1631-4562-a983-44424a426be7 node DatanodeRegistration(127.0.0.1, datanodeUuid=5de52943-ebf7-44b2-9eaf-75d5100a6c45, infoPort=47269, ipcPort=34281, storageInfo=lv=-56;cid=testClusterID;nsid=673753889;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3796413 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 3796693 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:52362 [junit4] 2> 3796738 INFO (IPC Server handler 8 on 56311) [ ] BlockStateChange BLOCK* processReport: from storage DS-b6404081-cac8-40ca-baea-f8e9edbbc86f node DatanodeRegistration(127.0.0.1, datanodeUuid=76b57766-c952-4ca7-b6bb-d927265ec4c0, infoPort=52362, ipcPort=32806, storageInfo=lv=-56;cid=testClusterID;nsid=673753889;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 3796738 INFO (IPC Server handler 8 on 56311) [ ] BlockStateChange BLOCK* processReport: from storage DS-ce96e45a-62be-496c-b195-13ddb28ce2b3 node DatanodeRegistration(127.0.0.1, datanodeUuid=76b57766-c952-4ca7-b6bb-d927265ec4c0, infoPort=52362, ipcPort=32806, storageInfo=lv=-56;cid=testClusterID;nsid=673753889;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 3796833 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 3796833 INFO (Thread-18239) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3796833 INFO (Thread-18239) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 3796933 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkTestServer start zk server on port:52129 [junit4] 2> 3796933 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3796934 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3796935 INFO (zkCallback-30454-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c3d262b name:ZooKeeperConnection Watcher:127.0.0.1:52129 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3796935 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3796936 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3796936 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3796937 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3796938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3796938 INFO (zkCallback-30455-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b67047e name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3796938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3796938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3796938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 3796940 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 3796941 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 3796941 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 3796942 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796942 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3796943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 3796944 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796944 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3796945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 3796945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 3796946 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796946 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 3796947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3796948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3796948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3796949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3796950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796950 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 3796951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3796951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3796952 INFO (zkCallback-30456-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c7e2fc3 name:ZooKeeperConnection Watcher:127.0.0.1:52129 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3796952 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3796952 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3796952 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 3796953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3796953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3796953 INFO (zkCallback-30457-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@265c3799 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3796953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3796954 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3796954 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796954 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 3796955 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796955 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 3796956 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796956 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 3796957 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796957 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3796958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796958 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 3796959 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796959 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 3796959 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 3796960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 3796961 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796961 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 3796962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796962 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 3796963 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796963 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 3796964 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3796964 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 3797031 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores/collection1 [junit4] 2> 3797034 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 3797039 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5fd37370{/mn/l,null,AVAILABLE} [junit4] 2> 3797039 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2a71dc83{HTTP/1.1}{127.0.0.1:58658} [junit4] 2> 3797040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.Server Started @3799795ms [junit4] 2> 3797040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=58658, solr.data.dir=hdfs://localhost:56311/hdfs__localhost_56311__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.5_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001_tempDir-002_control_data, hostContext=/mn/l, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores} [junit4] 2> 3797040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@3827de24 [junit4] 2> 3797040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001' [junit4] 2> 3797041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3797041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3797041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3797041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3797042 INFO (zkCallback-30458-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74e46485 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3797042 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3797042 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3797043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3797043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/solr.xml [junit4] 2> 3797057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores [junit4] 2> 3797057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.CoreContainer New CoreContainer 243778648 [junit4] 2> 3797057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001] [junit4] 2> 3797058 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/lib [junit4] 2> 3797058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3797062 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3797065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3797066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3797066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52129/solr [junit4] 2> 3797067 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3797067 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3797069 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3797072 INFO (zkCallback-30461-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d00b8e4 name:ZooKeeperConnection Watcher:127.0.0.1:52129 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3797073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3797075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3797078 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@7b45d3c4 [junit4] 2> 3797080 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3797083 INFO (zkCallback-30462-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@d509a67 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3797083 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3797086 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 3797089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 3797092 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 3797094 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 3797097 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 3797098 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3797100 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3797101 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3797102 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3797103 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58658_mn%2Fl [junit4] 2> 3797103 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58658_mn%2Fl [junit4] 2> 3797104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 3797106 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 3797108 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3797108 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000 [junit4] 2> 3797108 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58658_mn%2Fl [junit4] 2> 3797108 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 3797109 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.Overseer Overseer (id=96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) starting [junit4] 2> 3797111 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 3797114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3797115 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 3797115 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3797115 INFO (OverseerCollectionConfigSetProcessor-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 3797117 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3797143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 3797143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 3797144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores [junit4] 2> 3797144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, configSetProperties=configsetprops.json, shard=, collection=control_collection, schema=schema.xml, loadOnStartup=true, name=collection1, dataDir=data/, coreNodeName=, transient=false} [junit4] 2> 3797144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores/collection1 [junit4] 2> 3797144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 3797145 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 3797145 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 3797149 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3797149 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 3797150 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 3797150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1 [junit4] 2> 3797150 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "state":"down", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58658/mn/l";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:58658_mn%2Fl", [junit4] 2> "core":"collection1"} current state version: 0 [junit4] 2> 3797150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 3797151 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "state":"down", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58658/mn/l";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "node_name":"127.0.0.1:58658_mn%2Fl", [junit4] 2> "core":"collection1"} [junit4] 2> 3797156 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 3797156 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 3797156 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 3797158 INFO (zkCallback-30462-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3797158 INFO (zkCallback-30462-thread-2-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3798156 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 3798157 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 3798157 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 3798157 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 3798158 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 3798158 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores/collection1' [junit4] 2> 3798158 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3798158 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3798160 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 3798164 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 3798173 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.2 [junit4] 2> 3798192 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 3798197 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 3799182 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1738048980 [junit4] 2> 3799354 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3799364 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 3799366 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 3799375 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3799379 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 3799381 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3799381 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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/J1 [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:56311/solr_hdfs_home [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/control-001/cores/collection1], dataDir=[hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 3799383 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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@60ab1380 [junit4] 2> 3799384 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3799396 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 3799397 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 3799405 WARN (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:56311/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 3799405 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3799415 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:56311/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 3799416 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=9, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=1.2841796875, floorSegmentMB=0.25390625, forceMergeDeletesPctAllowed=5.759116282312718, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3799462 INFO (IPC Server handler 5 on 56311) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57089 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-b7012733-5ec2-47b7-a19e-2026b53c2f84:NORMAL:127.0.0.1:55499|RBW], ReplicaUnderConstruction[[DISK]DS-ce96e45a-62be-496c-b195-13ddb28ce2b3:NORMAL:127.0.0.1:57089|FINALIZED]]} size 0 [junit4] 2> 3799463 INFO (IPC Server handler 4 on 56311) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55499 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-b7012733-5ec2-47b7-a19e-2026b53c2f84:NORMAL:127.0.0.1:55499|RBW], ReplicaUnderConstruction[[DISK]DS-ce96e45a-62be-496c-b195-13ddb28ce2b3:NORMAL:127.0.0.1:57089|FINALIZED]]} size 0 [junit4] 2> 3799465 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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:56311/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fe35de6; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3799465 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3799466 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58658_mn%2Fl 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:56311/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799468 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799468 WARN (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799470 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3799471 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3799472 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3799473 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 3799477 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/threads,/update/json/docs,/admin/luke,/admin/file,standard,/admin/mbeans,/replication,/dump,/admin/logging,/update,/get,/update/json,/admin/plugins,/schema,/admin/properties,/admin/system,/admin/segments,/config,/update/csv,/admin/ping [junit4] 2> 3799478 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799478 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799478 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799478 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 3799487 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 3799487 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 3799491 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.799049105982103] [junit4] 2> 3799500 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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:56311/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1fe35de6; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 3799500 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 3799500 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@aad80ce[collection1] main [junit4] 2> 3799500 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799501 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799502 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 3799502 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 3799503 INFO (searcherExecutor-13157-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@aad80ce[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3799503 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799503 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799503 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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> 3799503 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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 1538003084402229248 [junit4] 2> 3799505 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl 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 1538003084402229248 [junit4] 2> 3799505 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 3799505 INFO (coreLoadExecutor-13156-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 3799505 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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:58658/mn/l collection:control_collection shard:shard1 [junit4] 2> 3799506 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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> 3799507 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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/96125192600223750-core_node1-n_0000000000 [junit4] 2> 3799507 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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> 3799510 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 3799510 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799510 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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> 3799510 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58658/mn/l/collection1/ [junit4] 2> 3799510 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 3799510 INFO (zkCallback-30462-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799510 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58658/mn/l/collection1/ has no replicas [junit4] 2> 3799510 INFO (zkCallback-30462-thread-3-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799512 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3799513 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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/96125192600223750-core_node1-n_0000000000 [junit4] 2> 3799514 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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:58658/mn/l/collection1/ shard1 [junit4] 2> 3799515 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] 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:58658/mn/l";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 3799616 INFO (zkCallback-30462-thread-1-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799617 INFO (zkCallback-30462-thread-3-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799665 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58658/mn/l/collection1/ and leader is http://127.0.0.1:58658/mn/l/collection1/ [junit4] 2> 3799665 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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:58658/mn/l [junit4] 2> 3799665 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 3799665 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 3799665 INFO (coreZkRegister-13150-thread-1-processing-s:shard1 c:control_collection n:127.0.0.1:58658_mn%2Fl r:core_node1 x:collection1) [n:127.0.0.1:58658_mn%2Fl 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> 3799667 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "state":"active", [junit4] 2> "numShards":"1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58658/mn/l";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:58658_mn%2Fl", [junit4] 2> "core":"collection1"} current state version: 3 [junit4] 2> 3799667 INFO (OverseerStateUpdate-96125192600223750-127.0.0.1:58658_mn%2Fl-n_0000000000) [n:127.0.0.1:58658_mn%2Fl ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "state":"active", [junit4] 2> "numShards":"1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:58658/mn/l";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "node_name":"127.0.0.1:58658_mn%2Fl", [junit4] 2> "core":"collection1"} [junit4] 2> 3799675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3799675 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3799676 INFO (zkCallback-30464-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ef1a486 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3799676 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3799676 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3799676 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3799677 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3799732 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/cores/collection1 [junit4] 2> 3799733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001 [junit4] 2> 3799733 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e04b841{/mn/l,null,AVAILABLE} [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5b19b024{HTTP/1.1}{127.0.0.1:59406} [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.e.j.s.Server Started @3802490ms [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/mn/l, hostPort=59406, solrconfig=solrconfig.xml, solr.data.dir=hdfs://localhost:56311/hdfs__localhost_56311__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.5_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001_tempDir-002_jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/cores} [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@3827de24 [junit4] 2> 3799735 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001' [junit4] 2> 3799736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3799736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 3799736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3799736 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3799737 INFO (zkCallback-30465-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@48b29374 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3799737 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3799737 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3799740 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 3799740 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/solr.xml [junit4] 2> 3799749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/cores [junit4] 2> 3799749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.CoreContainer New CoreContainer 496687040 [junit4] 2> 3799749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001] [junit4] 2> 3799749 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_CB8363A083754520-001/shard-1-001/lib [junit4] 2> 3799749 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] 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> 3799750 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52129/solr [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3799751 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3799752 INFO (zkCallback-30468-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@eba99f5 name:ZooKeeperConnection Watcher:127.0.0.1:52129 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3799752 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3799752 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3799753 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:59406_mn%2Fl ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$6@54e132d0 [junit4] 2> 3799753 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:59406_mn%2Fl ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3799754 INFO (zkCallback-30469-thread-1-processing-n:127.0.0.1:59406_mn%2Fl) [n:127.0.0.1:59406_mn%2Fl ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a4edcd8 name:ZooKeeperConnection Watcher:127.0.0.1:52129/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3799754 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:59406_mn%2Fl ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3799758 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[CB8363A083754520]) [n:127.0.0.1:59406_mn%2Fl ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3799769 INFO (zkCallback-30469-thread-1-processing-n:127.0.0.1:59406_mn%2Fl) [n:127.0.0.1:59406_mn%2Fl ] 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> 3799769 INFO (zkCallback-30462-thread-3-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799769 INFO (zkCallback-30462-thread-2-processing-n:127.0.0.1:58658_mn%2Fl) [n:127.0.0.1:58658_mn%2Fl ] 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> 3799769 INFO (zkCallback-30464-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> 3800761 INFO (TEST-HdfsCo [...truncated too long message...] 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> 4185047 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[CB8363A083754520]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jun 24, 2016 7:47:05 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {a_i1=BlockTreeOrds(blocksize=128), timestamp=BlockTreeOrds(blocksize=128), range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), range_facet_l=BlockTreeOrds(blocksize=128), other_tl1=FSTOrd50, intDefault=FSTOrd50, id=PostingsFormat(name=LuceneFixedGap), multiDefault=BlockTreeOrds(blocksize=128), a_t=BlockTreeOrds(blocksize=128), range_facet_i_dv=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=Direct), _version_=FSTOrd50}, docValues:{timestamp=DocValuesFormat(name=Direct), range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Direct)}, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=it-IT, timezone=America/Argentina/Catamarca [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=240671688,total=500170752 [junit4] 2> NOTE: All tests run in this JVM: [ParsingFieldUpdateProcessorsTest, TestReplicationHandlerBackup, TestSolrConfigHandlerCloud, TestSimpleTrackingShardHandler, TestSystemIdResolver, DistributedIntervalFacetingTest, TestDistributedGrouping, SpellCheckComponentTest, TestCursorMarkWithoutUniqueKey, VersionInfoTest, HdfsUnloadDistributedZkTest, CreateCollectionCleanupTest, TestDocBasedVersionConstraints, TestSolrDeletionPolicy2, TestDocumentBuilder, TestRTimerTree, TestQueryTypes, NumericFieldsTest, ReplicationFactorTest, TestBinaryResponseWriter, BasicAuthIntegrationTest, ConvertedLegacyTest, TestLFUCache, SuggestComponentTest, MultiTermTest, TestRequestForwarding, TestRecoveryHdfs, TestClassNameShortening, TestDFRSimilarityFactory, BigEndianAscendingWordDeserializerTest, OverseerTaskQueueTest, LoggingHandlerTest, TestSolrCloudWithKerberosAlt, SharedFSAutoReplicaFailoverTest, TermVectorComponentTest, MBeansHandlerTest, CloudMLTQParserTest, TestCodecSupport, TestComplexPhraseQParserPlugin, MultiThreadedOCPTest, TestStressVersions, SolrCmdDistributorTest, SecurityConfHandlerTest, ChangedSchemaMergeTest, SolrIndexSplitterTest, TestTrie, CollectionTooManyReplicasTest, ChaosMonkeyNothingIsSafeTest, TestRemoveLastDynamicCopyField, TestLMJelinekMercerSimilarityFactory, UUIDUpdateProcessorFallbackTest, TestDynamicFieldCollectionResource, TestSolrQueryParserDefaultOperatorResource, TestSurroundQueryParser, AddBlockUpdateTest, IndexBasedSpellCheckerTest, OpenCloseCoreStressTest, MinimalSchemaTest, ZkControllerTest, FileUtilsTest, HdfsNNFailoverTest, RequestLoggingTest, OpenExchangeRatesOrgProviderTest, UniqFieldsUpdateProcessorFactoryTest, CloneFieldUpdateProcessorFactoryTest, HdfsRestartWhileUpdatingTest, CloudExitableDirectoryReaderTest, BufferStoreTest, DeleteStatusTest, AnalyticsMergeStrategyTest, DeleteLastCustomShardedReplicaTest, ForceLeaderTest, TestSolrQueryResponse, SolrIndexConfigTest, TimeZoneUtilsTest, SimplePostToolTest, TestCoreContainer, LeaderInitiatedRecoveryOnCommitTest, EnumFieldTest, SchemaVersionSpecificBehaviorTest, FacetPivotSmallTest, TestFastLRUCache, DistributedTermsComponentTest, ZkSolrClientTest, HighlighterMaxOffsetTest, SpellCheckCollatorTest, TestImplicitCoreProperties, BasicFunctionalityTest, TestStandardQParsers, CSVRequestHandlerTest, MergeStrategyTest, TestFiltering, OverseerTest, ClusterStateUpdateTest, TestZkChroot, TestRandomDVFaceting, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, TestSort, TestIndexSearcher, SoftAutoCommitTest, SuggesterTSTTest, SuggesterTest, SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, DistanceFunctionTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest, LukeRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, FileBasedSpellCheckerTest, TermsComponentTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, FastVectorHighlighterTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, TestPartialUpdateDeduplication, HighlighterConfigTest, TestQuerySenderNoQuery, ResponseLogComponentTest, TestMergePolicyConfig, TestDocSet, TestSearchPerf, OutputWriterTest, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, SpellPossibilityIteratorTest, TestXIncludeConfig, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, PreAnalyzedFieldTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, SystemInfoHandlerTest, UUIDFieldTest, DistributedMLTComponentTest, TestRTGBase, TestTolerantSearch, ConnectionReuseTest, TestEmbeddedSolrServerConstructors, ActionThrottleTest, AliasIntegrationTest, CleanupOldIndexTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteReplicaTest, DistributedQueueTest, HttpPartitionTest, SimpleCollectionCreateDeleteTest, SolrCloudExampleTest, SolrXmlInZkTest, TestAuthenticationFramework, TestCloudDeleteByQuery, TestCloudInspectUtil, TestCloudPivotFacet, TestCollectionAPI, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterBase, TestMiniSolrCloudClusterSSL, TestOnReconnectListenerSupport, TestRebalanceLeaders, TestShortCircuitedRequests, TestStressLiveNodes, HdfsChaosMonkeyNothingIsSafeTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [471/573 (1!)] on J1 in 399.35s, 1 test, 1 error <<< FAILURES! [...truncated 344 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: CB8363A083754520] Total time: 204 minutes 48 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: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
