Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/727/
2 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=13305, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13305, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:41812/y_: Could not find collection : awholynewstresscollection_collection2_3 at __randomizedtesting.SeedInfo.seed([DA3C2C08DCF8DF33]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=11727, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=11727, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:55577/nj_: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([DA3C2C08DCF8DF33]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10507 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/init-core-data-001 [junit4] 2> 1456678 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[DA3C2C08DCF8DF33]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1456678 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[DA3C2C08DCF8DF33]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /y_/ [junit4] 2> 1456682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1456687 INFO (Thread-7236) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1456687 INFO (Thread-7236) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1456788 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkTestServer start zk server on port:59824 [junit4] 2> 1456788 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1456793 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1456804 INFO (zkCallback-1613-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@42b68ec2 name:ZooKeeperConnection Watcher:127.0.0.1:59824 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1456804 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1456805 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1456805 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1456809 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1456822 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1456860 INFO (zkCallback-1614-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46c65493 name:ZooKeeperConnection Watcher:127.0.0.1:59824/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1456860 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1456861 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1456861 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1456863 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1456873 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1456878 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1456881 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1456881 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1456889 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1456889 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1456898 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1456898 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1456903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1456904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1456907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1456908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1456910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1456911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1456918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1456918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1456925 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1456925 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1456930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1456930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1456935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1456935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1456937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1456937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1457501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1 [junit4] 2> 1457503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1457506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2876a4e9{/y_,null,AVAILABLE} [junit4] 2> 1457507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5add29d6{HTTP/1.1}{127.0.0.1:56734} [junit4] 2> 1457507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.Server Started @1460772ms [junit4] 2> 1457507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/tempDir-001/control/data, hostContext=/y_, hostPort=56734, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores} [junit4] 2> 1457508 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1457508 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/' [junit4] 2> 1457544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/solr.xml [junit4] 2> 1457556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores [junit4] 2> 1457556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer New CoreContainer 1874121304 [junit4] 2> 1457556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/] [junit4] 2> 1457557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/lib [junit4] 2> 1457557 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/lib). [junit4] 2> 1457567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] 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> 1457568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1457572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1457572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1457573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59824/solr [junit4] 2> 1457573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1457573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1457575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1457577 INFO (zkCallback-1616-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ec2ceb8 name:ZooKeeperConnection Watcher:127.0.0.1:59824 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1457578 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1457579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1457580 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x14e42d13c670002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1457581 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1457584 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2de31823 name:ZooKeeperConnection Watcher:127.0.0.1:59824/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1457584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1457586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1457591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1457596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1457601 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1457606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1457610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1457612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1457613 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1457615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56734_y_ [junit4] 2> 1457615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:56734_y_ [junit4] 2> 1457618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1457621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1457623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1457623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94086108650340355-127.0.0.1:56734_y_-n_0000000000 [junit4] 2> 1457625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56734_y_ [junit4] 2> 1457625 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1457628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer Overseer (id=94086108650340355-127.0.0.1:56734_y_-n_0000000000) starting [junit4] 2> 1457630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1457638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1457639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1457639 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1457639 INFO (OverseerCollectionProcessor-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 1457645 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1457646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1457647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores [junit4] 2> 1457647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1457648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/ [junit4] 2> 1457648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1457650 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1457650 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1457650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1457651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:56734_y_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1457652 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1457652 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56734/y_";, [junit4] 2> "node_name":"127.0.0.1:56734_y_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1457653 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56734/y_";, [junit4] 2> "node_name":"127.0.0.1:56734_y_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1457653 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1457654 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1457656 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] 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> 1457658 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 1457660 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1458652 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1458652 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1458653 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1458653 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1458654 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1458654 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/' [junit4] 2> 1458681 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1458689 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1458695 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1458712 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1458714 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1458722 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1458874 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1458881 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1458882 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1458895 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1458898 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1458901 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1458902 WARN (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1458902 WARN (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1458903 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1458904 WARN (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1458904 WARN (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1458904 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1458904 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1458905 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1458905 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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@230a32ff [junit4] 2> 1458905 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data [junit4] 2> 1458906 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data/index/ [junit4] 2> 1458906 WARN (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1458906 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data/index [junit4] 2> 1458906 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.05078125, noCFSRatio=0.12028035340369235] [junit4] 2> 1458908 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@16cda16b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75888bdf; maxCacheMB=0.021484375 maxMergeSizeMB=0.681640625)),segFN=segments_1,generation=1} [junit4] 2> 1458908 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1458908 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56734_y_ 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 /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data/ [junit4] 2> 1458909 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1458920 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1458920 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1458922 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1458922 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1458924 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1458924 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458925 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458926 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458926 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458929 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1458930 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1458931 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1458932 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1458938 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1458938 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458940 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 1458940 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458942 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1458942 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1458942 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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=45, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=34.328125, floorSegmentMB=1.140625, forceMergeDeletesPctAllowed=28.55199774250088, segmentsPerTier=19.0, maxCFSSegmentSizeMB=0.68359375, noCFSRatio=0.5735270532628113 [junit4] 2> 1458943 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@16cda16b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@75888bdf; maxCacheMB=0.021484375 maxMergeSizeMB=0.681640625)),segFN=segments_1,generation=1} [junit4] 2> 1458943 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1458943 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@42fd9269[collection1] main [junit4] 2> 1458943 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458945 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458945 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458946 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1458947 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1458947 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458947 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458948 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458948 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1458949 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1458950 INFO (searcherExecutor-2939-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@42fd9269[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1458950 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458950 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458950 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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> 1458950 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ 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 1505377740774178816 [junit4] 2> 1458952 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1505377740774178816 [junit4] 2> 1458953 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1458953 INFO (coreLoadExecutor-2938-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1458954 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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:56734/y_ collection:control_collection shard:shard1 [junit4] 2> 1458955 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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> 1458960 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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/94086108650340355-core_node1-n_0000000000 [junit4] 2> 1458961 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1458963 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1458964 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1458964 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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> 1458964 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56734/y_/collection1/ [junit4] 2> 1458964 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1458965 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:56734/y_/collection1/ has no replicas [junit4] 2> 1458965 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] 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> 1458965 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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:56734/y_/collection1/ shard1 [junit4] 2> 1458965 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1458982 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1458983 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] 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:56734/y_";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1459087 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] 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> 1459089 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 1459134 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:56734/y_/collection1/ and leader is http://127.0.0.1:56734/y_/collection1/ [junit4] 2> 1459135 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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:56734/y_ [junit4] 2> 1459135 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1459135 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1459135 INFO (coreZkRegister-2932-thread-1-processing-n:127.0.0.1:56734_y_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56734_y_ 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> 1459136 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1459137 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56734/y_";, [junit4] 2> "node_name":"127.0.0.1:56734_y_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1459138 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56734/y_";, [junit4] 2> "node_name":"127.0.0.1:56734_y_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1459152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1459156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1459158 INFO (zkCallback-1619-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@417cfcfd name:ZooKeeperConnection Watcher:127.0.0.1:59824/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1459158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1459158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1459158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1459165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1459165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1459165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1459166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1459170 INFO (zkCallback-1620-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e8f614c name:ZooKeeperConnection Watcher:127.0.0.1:59824/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1459170 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1459170 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1459172 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1459173 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 1459173 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1459173 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] 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> 1459174 INFO (zkCallback-1619-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> 1459179 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 1459180 INFO (zkCallback-1619-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 1459283 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1459284 INFO (zkCallback-1619-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> 1459284 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] 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> 1459285 INFO (zkCallback-1619-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1459286 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1459695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1 [junit4] 2> 1459696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001 [junit4] 2> 1459697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1459709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22781dae{/y_,null,AVAILABLE} [junit4] 2> 1459710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.ServerConnector Started ServerConnector@68c11357{HTTP/1.1}{127.0.0.1:47430} [junit4] 2> 1459710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.e.j.s.Server Started @1462975ms [junit4] 2> 1459711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/y_, hostPort=47430, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores} [junit4] 2> 1459711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 1459711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/' [junit4] 2> 1459753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/solr.xml [junit4] 2> 1459761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores [junit4] 2> 1459761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer New CoreContainer 358460161 [junit4] 2> 1459762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/] [junit4] 2> 1459762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/lib [junit4] 2> 1459764 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/lib). [junit4] 2> 1459775 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] 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> 1459776 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1459777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1459777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1459777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59824/solr [junit4] 2> 1459777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1459777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1459778 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1459779 INFO (zkCallback-1622-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@382ac221 name:ZooKeeperConnection Watcher:127.0.0.1:59824 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1459780 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1459781 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1459785 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1459787 INFO (zkCallback-1623-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4e679d0e name:ZooKeeperConnection Watcher:127.0.0.1:59824/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1459787 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1459795 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1460800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:47430_y_ [junit4] 2> 1460801 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:47430_y_ [junit4] 2> 1460806 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1460806 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94086108650340359-127.0.0.1:47430_y_-n_0000000001 [junit4] 2> 1460807 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94086108650340355-127.0.0.1:56734_y_-n_0000000000 to know if I could be the leader [junit4] 2> 1460808 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1460808 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1460809 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores [junit4] 2> 1460810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1460810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/ [junit4] 2> 1460810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1460812 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1460812 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[DA3C2C08DCF8DF33]) [n:127.0.0.1:47430_y_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1460816 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1460816 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1460820 INFO (zkCallback-1617-thread-1-processing-n:127.0.0.1:56734_y_) [n:127.0.0.1:56734_y_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1460821 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:47430/y_";, [junit4] 2> "node_name":"127.0.0.1:47430_y_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1460821 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:47430/y_";, [junit4] 2> "node_name":"127.0.0.1:47430_y_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1460821 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1460821 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1460822 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1 [junit4] 2> 1460823 INFO (OverseerStateUpdate-94086108650340355-127.0.0.1:56734_y_-n_0000000000) [n:127.0.0.1:56734_y_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1460823 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 1460823 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1460824 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 1 [junit4] 2> 1460825 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1460825 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1460825 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1460825 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1460826 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1460827 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1460827 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/' [junit4] 2> 1460861 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1460871 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1460880 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1460898 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1460899 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1460913 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1461142 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1461151 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1461152 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1461165 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1461169 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1461175 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1461177 WARN (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1461178 WARN (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1461178 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1461179 WARN (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1461179 WARN (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1461179 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1461179 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1461180 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/], dataDir=[null] [junit4] 2> 1461181 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 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@230a32ff [junit4] 2> 1461181 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/data [junit4] 2> 1461181 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 1461182 WARN (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1461182 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_DA3C2C08DCF8DF33-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1461182 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.05078125, noCFSRatio=0.12028035340369235] [junit4] 2> 1461184 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@af2da88 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7acd066; maxCacheMB=0.021484375 maxMergeSizeMB=0.681640625)),segFN=segments_1,generation=1} [junit4] 2> 1461184 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1461188 INFO (coreLoadExecutor-2949-thread-1-processing-n:127.0.0.1:47430_y_) [n:127.0.0.1:47430_y_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1461188 [...truncated too long message...] [DA3C2C08DCF8DF33]-worker) [n:127.0.0.1:50622_nj_ c:addReplicaColl s:shard2 r:core_node1 x:addReplicaColl_shard2_replica2] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0 [junit4] 2> 2464720 ERROR (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DA3C2C08DCF8DF33]-worker) [n:127.0.0.1:50622_nj_ c:addReplicaColl s:shard2 r:core_node1 x:addReplicaColl_shard2_replica2] o.a.h.m.l.MethodMetric Error invoking method getBlocksTotal [junit4] 2> java.lang.reflect.InvocationTargetException [junit4] 2> at sun.reflect.GeneratedMethodAccessor117.invoke(Unknown Source) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483) [junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111) [junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 2464723 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[DA3C2C08DCF8DF33]-worker) [n:127.0.0.1:50622_nj_ c:addReplicaColl s:shard2 r:core_node1 x:addReplicaColl_shard2_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jun 30, 2015 1:11:34 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=ar_BH, timezone=Australia/Darwin [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_25 (64-bit)/cpus=4,threads=2,free=223768896,total=527433728 [junit4] 2> NOTE: All tests run in this JVM: [StressHdfsTest, CoreMergeIndexesAdminHandlerTest, TestAuthorizationFramework, CloudMLTQParserTest, OverriddenZkACLAndCredentialsProvidersTest, TestDynamicFieldCollectionResource, TestQueryTypes, RecoveryZkTest, TestCursorMarkWithoutUniqueKey, BadIndexSchemaTest, LeaderElectionTest, CollectionReloadTest, TestReqParamsAPI, TestSmileRequest, FileBasedSpellCheckerTest, TestSearcherReuse, TestGroupingSearch, DistribJoinFromCollectionTest, AddBlockUpdateTest, DirectSolrSpellCheckerTest, SolrInfoMBeanTest, RollingRestartTest, TestReload, SolrCoreCheckLockOnStartupTest, SimpleCollectionCreateDeleteTest, TestSchemaSimilarityResource, HdfsLockFactoryTest, QueryParsingTest, HdfsRecoveryZkTest, FullSolrCloudDistribCmdsTest, TestHdfsUpdateLog, TestBinaryResponseWriter, TestQuerySenderNoQuery, SynonymTokenizerTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [202/514] on J2 in 1156.32s, 1 test, 1 error <<< FAILURES! [...truncated 980 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 514 suites, 2021 tests, 2 errors, 20 ignored (10 assumptions) Total time: 189 minutes 31 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
