Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/808/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=525, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=525, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:44547, http://127.0.0.1:44119, http://127.0.0.1:46858, http://127.0.0.1:43713, http://127.0.0.1:56085] at __randomizedtesting.SeedInfo.seed([12D1917747A76D63]:0) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:898) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:44547, http://127.0.0.1:44119, http://127.0.0.1:46858, http://127.0.0.1:43713, http://127.0.0.1:56085] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:43713 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:587) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) ... 5 more Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:480) ... 9 more FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=21280, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=21280, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:50090/_vep/r: Could not find collection : awholynewstresscollection_collection2_0 at __randomizedtesting.SeedInfo.seed([12D1917747A76D63]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Build Log: [...truncated 9913 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/init-core-data-001 [junit4] 2> 0 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[12D1917747A76D63]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 50 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[12D1917747A76D63]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 98 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 107 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 108 INFO (Thread-1) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 207 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkTestServer start zk server on port:53425 [junit4] 2> 260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 359 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 614 INFO (zkCallback-1-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c132da name:ZooKeeperConnection Watcher:127.0.0.1:53425 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 675 INFO (zkCallback-2-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@9ce1e06 name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 675 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 699 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 705 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 750 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 750 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 754 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 768 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 769 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 781 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 781 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 785 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 785 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 803 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 804 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 818 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 819 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2051 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1 [junit4] 2> 2100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.u.log Logging initialized @5509ms [junit4] 2> 2309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 2407 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6773ee1f{/,null,AVAILABLE} [junit4] 2> 2438 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.ServerConnector Started ServerConnector@726cb493{HTTP/1.1}{127.0.0.1:56085} [junit4] 2> 2440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.Server Started @5848ms [junit4] 2> 2440 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/tempDir-001/control/data, hostContext=/, hostPort=56085, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores} [junit4] 2> 2448 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2480 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/' [junit4] 2> 2691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2708 INFO (zkCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69ff993d name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2719 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/solr.xml [junit4] 2> 2863 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores [junit4] 2> 2934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer New CoreContainer 79155045 [junit4] 2> 2934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/] [junit4] 2> 2934 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/lib [junit4] 2> 2935 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/lib). [junit4] 2> 2975 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 3456 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3490 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53425/solr [junit4] 2> 3528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 3528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 3535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3570 INFO (zkCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70754265 name:ZooKeeperConnection Watcher:127.0.0.1:53425 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 3588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 3595 INFO (zkCallback-6-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c872440 name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3595 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 3605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 3630 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 3641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 3651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 3659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 3682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 3689 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 3692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 3694 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 3696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56085_ [junit4] 2> 3698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:56085_ [junit4] 2> 3706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 3710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 3713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 3715 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94602791184891908-127.0.0.1:56085_-n_0000000000 [junit4] 2> 3721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56085_ [junit4] 2> 3721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 3730 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.Overseer Overseer (id=94602791184891908-127.0.0.1:56085_-n_0000000000) starting [junit4] 2> 3740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 3794 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3871 INFO (OverseerCollectionConfigSetProcessor-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 3883 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 3897 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4019 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4019 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4084 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores [junit4] 2> 4193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 4197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/ [junit4] 2> 4198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 4242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:56085_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4246 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4247 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4255 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4255 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:56085";, [junit4] 2> "node_name":"127.0.0.1:56085_", [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> 4289 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:56085";, [junit4] 2> "node_name":"127.0.0.1:56085_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4291 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4308 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4321 INFO (zkCallback-6-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] 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> 5256 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 5256 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 5260 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 5260 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 5267 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5268 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/' [junit4] 2> 5325 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 5403 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 5458 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 5623 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 5637 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5787 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 6515 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 6560 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 6565 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 6863 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 6874 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 6888 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 6891 WARN (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 6891 WARN (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 6892 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 6893 WARN (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 6893 WARN (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 6897 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 6899 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 6923 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 6939 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 6943 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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@773ad071 [junit4] 2> 7039 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data [junit4] 2> 7040 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data/index/ [junit4] 2> 7040 WARN (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 7061 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data/index [junit4] 2> 7064 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 7186 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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(RAMDirectory@3c605837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77527225),segFN=segments_1,generation=1} [junit4] 2> 7187 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 7224 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data/ [junit4] 2> 7224 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:56085_ 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 7636 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 7657 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 7662 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 7663 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 7663 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 7663 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7697 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7697 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7701 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7772 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 7783 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 7789 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 7791 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 7847 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7848 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7896 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7896 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 7923 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 7923 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 7924 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6d1de277 [junit4] 2> 7939 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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(RAMDirectory@3c605837 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@77527225),segFN=segments_1,generation=1} [junit4] 2> 7939 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 7975 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@526d0124[collection1] main [junit4] 2> 7991 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8011 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8011 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8015 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 8015 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 8067 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8068 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8068 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8068 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 8125 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 8127 INFO (searcherExecutor-8-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@526d0124[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 8130 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8132 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8132 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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> 8132 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ 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 1513644667220000768 [junit4] 2> 8149 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 17.0ms to seed version buckets with highest version 1513644667220000768 [junit4] 2> 8151 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 8155 INFO (coreLoadExecutor-7-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 8177 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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:56085 collection:control_collection shard:shard1 [junit4] 2> 8203 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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> 8225 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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/94602791184891908-core_node1-n_0000000000 [junit4] 2> 8226 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 8229 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 8229 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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> 8231 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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> 8231 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56085/collection1/ [junit4] 2> 8231 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 8231 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:56085/collection1/ has no replicas [junit4] 2> 8232 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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:56085/collection1/ shard1 [junit4] 2> 8236 INFO (zkCallback-6-thread-1-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] 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> 8256 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 8262 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 8296 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:56085";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 8323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 8347 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 8371 INFO (zkCallback-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@56ac5204 name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 8371 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 8371 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 8372 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 8382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 8382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 8383 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 8399 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 8419 INFO (zkCallback-9-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e266ed2 name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 8421 INFO (zkCallback-6-thread-2-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] 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> 8421 INFO (zkCallback-8-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> 8422 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 8422 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 8431 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 8432 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 8447 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:56085/collection1/ and leader is http://127.0.0.1:56085/collection1/ [junit4] 2> 8449 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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:56085 [junit4] 2> 8449 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 8449 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 8449 INFO (coreZkRegister-1-thread-1-processing-n:127.0.0.1:56085_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:56085_ 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> 8458 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:56085";, [junit4] 2> "node_name":"127.0.0.1:56085_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 8458 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 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:56085";, [junit4] 2> "node_name":"127.0.0.1:56085_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 8460 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 8575 INFO (zkCallback-6-thread-2-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] 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> 8575 INFO (zkCallback-8-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> 8962 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores/collection1 [junit4] 2> 8964 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001 [junit4] 2> 8965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 9007 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14650135{/,null,AVAILABLE} [junit4] 2> 9011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78d9d05{HTTP/1.1}{127.0.0.1:43713} [junit4] 2> 9011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.e.j.s.Server Started @12420ms [junit4] 2> 9011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=43713, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores} [junit4] 2> 9012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 9012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/' [junit4] 2> 9049 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 9060 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 9074 INFO (zkCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20192d9b name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 9075 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 9076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 9087 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 9087 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/solr.xml [junit4] 2> 9107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores [junit4] 2> 9107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer New CoreContainer 1298522161 [junit4] 2> 9107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/] [junit4] 2> 9108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/lib [junit4] 2> 9108 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/lib). [junit4] 2> 9132 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] 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> 9134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 9139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 9139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 9139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53425/solr [junit4] 2> 9139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 9139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 9152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 9165 INFO (zkCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5a7c24df name:ZooKeeperConnection Watcher:127.0.0.1:53425 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 9166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 9166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 9183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 9187 INFO (zkCallback-13-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15e6ba3b name:ZooKeeperConnection Watcher:127.0.0.1:53425/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 9187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 9204 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 10213 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43713_ [junit4] 2> 10214 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43713_ [junit4] 2> 10216 INFO (zkCallback-6-thread-2-processing-n:127.0.0.1:56085_) [n:127.0.0.1:56085_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 10216 INFO (zkCallback-13-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 10216 INFO (zkCallback-8-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 10224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 10225 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94602791184891913-127.0.0.1:43713_-n_0000000001 [junit4] 2> 10228 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94602791184891908-127.0.0.1:56085_-n_0000000000 to know if I could be the leader [junit4] 2> 10257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 10257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 10259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores [junit4] 2> 10260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 10260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores/collection1/ [junit4] 2> 10261 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 10263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 10263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[12D1917747A76D63]) [n:127.0.0.1:43713_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 10271 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 10271 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 10274 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 10274 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 10275 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:43713";, [junit4] 2> "node_name":"127.0.0.1:43713_", [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> 10276 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] 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:43713";, [junit4] 2> "node_name":"127.0.0.1:43713_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 10276 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 10276 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 10277 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for collection1 ver 0 [junit4] 2> 10277 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 10280 INFO (OverseerStateUpdate-94602791184891908-127.0.0.1:56085_-n_0000000000) [n:127.0.0.1:56085_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 10282 INFO (zkCallback-13-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 10283 INFO (zkCallback-13-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ ] o.a.s.c.c.ZkStateReader Updating data for collection1 from 0 to 1 [junit4] 2> 11277 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 11277 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 11278 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 11278 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 11279 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 11279 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_12D1917747A76D63-001/shard-1-001/cores/collection1/' [junit4] 2> 11297 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 11310 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 11317 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 11331 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 11337 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 11379 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 11533 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 11546 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 11548 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 11574 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 11579 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 11583 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 11585 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 11585 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 11585 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 11586 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 11586 WARN (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 11587 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 11588 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 11588 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 11588 INFO (coreLoadExecutor-18-thread-1-processing-n:127.0.0.1:43713_) [n:127.0.0.1:43713_ c:collecti [...truncated too long message...] ver.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:53) [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:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) [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:367) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 6767694 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[12D1917747A76D63]-worker) [n:127.0.0.1:35485__vep%2Fr c:addReplicaColl s:shard1 r:core_node7 x:propertyDotName] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Sep 29, 2015 8:46:43 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53), sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=es_AR, timezone=America/Indiana/Marengo [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=256307136,total=534773760 [junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIDistributedZkTest, BlockCacheTest, CSVRequestHandlerTest, TestReloadDeadlock, TestObjectReleaseTracker, TestBinaryResponseWriter, TestConfigSets, TestSuggestSpellingConverter, SolrInfoMBeanTest, RulesTest, TestFiltering, TestAuthenticationFramework, TestSolrDeletionPolicy2, BJQParserTest, ZkSolrClientTest, ShardSplitTest, DistributedFacetPivotWhiteBoxTest, TestUtils, AddSchemaFieldsUpdateProcessorFactoryTest, ZkStateReaderTest, TestQuerySenderListener, TestRebalanceLeaders, SpatialHeatmapFacetsTest, SolrCloudExampleTest, TestWordDelimiterFilterFactory, ExplicitHLLTest, ChaosMonkeyNothingIsSafeTest, TestStressVersions, TestQuerySenderNoQuery, TestLFUCache, DocValuesMissingTest, TestFileDictionaryLookup, DocExpirationUpdateProcessorFactoryTest, TestConfigOverlay, SSLMigrationTest, TestReqParamsAPI, TestCloudPivotFacet, TermsComponentTest, UniqFieldsUpdateProcessorFactoryTest, DistributedQueueTest, TestCustomDocTransformer, SpatialRPTFieldTypeTest, SimplePostToolTest, DocValuesTest, URLClassifyProcessorTest, AnalysisErrorHandlingTest, StressHdfsTest, SolrIndexSplitterTest, OpenCloseCoreStressTest, TestDFRSimilarityFactory, NumericFieldsTest, HdfsRecoverLeaseTest, HLLSerializationTest, SimpleMLTQParserTest, TestHashPartitioner, IndexBasedSpellCheckerTest, CdcrRequestHandlerTest, ChaosMonkeySafeLeaderTest, ClusterStateUpdateTest, SortByFunctionTest, SuggesterFSTTest, TestManagedSchemaFieldTypeResource, FullHLLTest, DirectSolrSpellCheckerTest, DocumentBuilderTest, TestCodecSupport, TestCryptoKeys, NotRequiredUniqueKeyTest, SpellCheckCollatorTest, TestConfigSetsAPIExclusivity, TestFieldTypeResource, TestBlendedInfixSuggestions, TestMacros, TestMaxScoreQueryParser, TestCustomSort, MoreLikeThisHandlerTest, MultiTermTest, TestSchemaNameResource, MergeStrategyTest, SchemaVersionSpecificBehaviorTest, TestDistribDocBasedVersion, ConvertedLegacyTest, SpellCheckComponentTest, UpdateRequestProcessorFactoryTest, PKIAuthenticationIntegrationTest, TestDistributedGrouping, ZkCLITest, DistributedTermsComponentTest, SolrCoreTest, SolrCmdDistributorTest, TestIndexSearcher, SoftAutoCommitTest, TestTrie, NoCacheHeaderTest, TestUpdate, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, DebugComponentTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestQueryTypes, TestOmitPositions, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, ReturnFieldsTest, TestCSVResponseWriter, BinaryUpdateRequestHandlerTest, HighlighterConfigTest, SOLR749Test, UpdateParamsTest, AlternateDirectoryTest, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, CustomCollectionTest, LeaderFailoverAfterPartitionTest, MultiThreadedOCPTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestCloudInspectUtil, TestCollectionAPI, TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudClusterSSL, TestRequestStatusCollectionAPI, TestShortCircuitedRequests, TestSolrCloudWithKerberosAlt, VMParamsZkACLAndCredentialsProvidersTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [546/546] on J1 in 3129.45s, 1 test, 1 error <<< FAILURES! [...truncated 12 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 546 suites (8 ignored), 2145 tests, 2 errors, 29 ignored (17 assumptions) [seed: 12D1917747A76D63] Total time: 243 minutes 33 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
