Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/805/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=9170, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=9170, 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:54652, http://127.0.0.1:46509, http://127.0.0.1:55640, http://127.0.0.1:51525, http://127.0.0.1:52854] at __randomizedtesting.SeedInfo.seed([48D1D9C482DB894E]: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:54652, http://127.0.0.1:46509, http://127.0.0.1:55640, http://127.0.0.1:51525, http://127.0.0.1:52854] 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:51525 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=36977, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=36977, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:49731/ev_sff/f: Could not find collection : awholynewstresscollection_collection2_1 at __randomizedtesting.SeedInfo.seed([48D1D9C482DB894E]: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 10494 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_48D1D9C482DB894E-001/init-core-data-001 [junit4] 2> 1617999 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[48D1D9C482DB894E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1617999 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[48D1D9C482DB894E]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1618001 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1618002 INFO (Thread-4483) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1618002 INFO (Thread-4483) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1618102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkTestServer start zk server on port:41284 [junit4] 2> 1618102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618104 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618106 INFO (zkCallback-825-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4cfddb22 name:ZooKeeperConnection Watcher:127.0.0.1:41284 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618106 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618106 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618106 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1618109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618114 INFO (zkCallback-826-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@52fa12f4 name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618114 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1618116 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1618118 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1618119 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1618121 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618121 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1618123 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618123 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1618126 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618126 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1618128 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618128 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1618129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1618131 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618131 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1618133 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618133 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1618134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618135 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1618136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618136 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1618138 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618138 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1618140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1618142 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618143 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618144 INFO (zkCallback-827-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@425491bd name:ZooKeeperConnection Watcher:127.0.0.1:41284 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618144 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1618146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618147 INFO (zkCallback-828-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cc4a04c name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618147 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1618148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1618150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1618150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1618152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1618152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1618153 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1618153 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1618155 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1618155 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1618159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1618159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1618161 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1618162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1618164 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1618164 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1618166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1618167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1618168 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1618169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1618170 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1618170 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1618172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1618172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1618492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/cores/collection1 [junit4] 2> 1618494 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1618495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a749c9b{/,null,AVAILABLE} [junit4] 2> 1618500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@79f9a000{HTTP/1.1}{127.0.0.1:51525} [junit4] 2> 1618500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.Server Started @1621893ms [junit4] 2> 1618500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/tempDir-001/control/data, hostContext=/, hostPort=51525, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_48D1D9C482DB894E-001/control-001/cores} [junit4] 2> 1618500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1618500 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/' [junit4] 2> 1618515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618516 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618517 INFO (zkCallback-829-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@af43e43 name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618519 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1618519 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/solr.xml [junit4] 2> 1618524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/cores [junit4] 2> 1618524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.CoreContainer New CoreContainer 1764055661 [junit4] 2> 1618524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/] [junit4] 2> 1618524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/lib [junit4] 2> 1618524 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/control-001/lib). [junit4] 2> 1618531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1618532 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1618533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1618533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1618533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41284/solr [junit4] 2> 1618533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1618533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1618534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618535 INFO (zkCallback-831-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ad08f1a name:ZooKeeperConnection Watcher:127.0.0.1:41284 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1618541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1618542 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@46c6a72a name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1618542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1618543 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1618545 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1618548 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1618549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1618551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1618553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1618554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1618555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1618556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1618557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51525_ [junit4] 2> 1618557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51525_ [junit4] 2> 1618559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1618562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1618563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1618563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94587523969384454-127.0.0.1:51525_-n_0000000000 [junit4] 2> 1618564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51525_ [junit4] 2> 1618564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1618565 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.Overseer Overseer (id=94587523969384454-127.0.0.1:51525_-n_0000000000) starting [junit4] 2> 1618567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1618573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1618574 INFO (OverseerCollectionConfigSetProcessor-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1618574 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1618574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1618591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1618591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1618592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] 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_48D1D9C482DB894E-001/control-001/cores [junit4] 2> 1618593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] 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_48D1D9C482DB894E-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_48D1D9C482DB894E-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1618593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] 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_48D1D9C482DB894E-001/control-001/cores/collection1/ [junit4] 2> 1618593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1618594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1618594 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1618594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:51525_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1618594 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1618596 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:51525";, [junit4] 2> "node_name":"127.0.0.1:51525_", [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> 1618597 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:51525";, [junit4] 2> "node_name":"127.0.0.1:51525_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1618597 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1618597 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1618598 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1618599 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] 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> 1619598 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1619598 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1619598 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1619599 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1619599 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1619599 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/' [junit4] 2> 1619615 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1619621 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1619625 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1619634 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1619635 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1619640 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1619860 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1619884 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1619887 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1619897 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1619900 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1619901 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1619902 WARN (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1619902 WARN (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1619902 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1619906 WARN (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1619906 WARN (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1619908 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619908 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1619908 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1619908 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1619908 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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@c97ab7b [junit4] 2> 1619909 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data [junit4] 2> 1619909 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data/index/ [junit4] 2> 1619909 WARN (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1619909 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data/index [junit4] 2> 1619910 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=41, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=37.6650390625, floorSegmentMB=1.2578125, forceMergeDeletesPctAllowed=7.364170767629922, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4569280817884326 [junit4] 2> 1619910 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@1f36160e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48c49040; maxCacheMB=0.4443359375 maxMergeSizeMB=0.4521484375)),segFN=segments_1,generation=1} [junit4] 2> 1619911 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1619914 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data/ [junit4] 2> 1619914 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1619914 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51525_ 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_48D1D9C482DB894E-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1619914 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1619915 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1619915 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1619915 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1619915 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619915 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619916 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619916 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619918 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1619920 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1619920 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1619921 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1619929 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619929 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619930 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619930 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619931 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1619931 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1619931 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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=9, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.7900390625, noCFSRatio=0.7197987433015949] [junit4] 2> 1619932 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@1f36160e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@48c49040; maxCacheMB=0.4443359375 maxMergeSizeMB=0.4521484375)),segFN=segments_1,generation=1} [junit4] 2> 1619932 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1619932 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@71a9b040[collection1] main [junit4] 2> 1619932 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619933 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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> 1619934 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ 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 1513400385534951424 [junit4] 2> 1619936 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1513400385534951424 [junit4] 2> 1619936 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1619937 INFO (coreLoadExecutor-3082-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1619938 INFO (searcherExecutor-3083-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71a9b040[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1619940 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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:51525 collection:control_collection shard:shard1 [junit4] 2> 1619942 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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> 1619945 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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/94587523969384454-core_node1-n_0000000000 [junit4] 2> 1619945 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1619953 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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> 1619961 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1619961 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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> 1619961 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51525/collection1/ [junit4] 2> 1619962 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1619962 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51525/collection1/ has no replicas [junit4] 2> 1619962 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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:51525/collection1/ shard1 [junit4] 2> 1619963 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1619967 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1619970 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:51525";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1620072 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] 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> 1620099 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1620108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1620108 INFO (zkCallback-834-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@53f36972 name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1620108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1620109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1620109 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1620119 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1620122 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:51525/collection1/ and leader is http://127.0.0.1:51525/collection1/ [junit4] 2> 1620122 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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:51525 [junit4] 2> 1620122 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1620122 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1620122 INFO (coreZkRegister-3076-thread-1-processing-n:127.0.0.1:51525_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51525_ 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> 1620124 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:51525";, [junit4] 2> "node_name":"127.0.0.1:51525_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1620125 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:51525";, [junit4] 2> "node_name":"127.0.0.1:51525_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1620227 INFO (zkCallback-834-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> 1620227 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] 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> 1620467 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/cores/collection1 [junit4] 2> 1620468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001 [junit4] 2> 1620469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@47448441{/,null,AVAILABLE} [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@38f6ed4c{HTTP/1.1}{127.0.0.1:54652} [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.e.j.s.Server Started @1623865ms [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=54652, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_48D1D9C482DB894E-001/shard-1-001/cores} [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1620471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/' [junit4] 2> 1620486 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1620487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1620488 INFO (zkCallback-835-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d63c96 name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1620489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1620489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1620490 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1620490 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/solr.xml [junit4] 2> 1620495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/cores [junit4] 2> 1620495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.CoreContainer New CoreContainer 550555205 [junit4] 2> 1620495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/] [junit4] 2> 1620495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/lib [junit4] 2> 1620496 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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_48D1D9C482DB894E-001/shard-1-001/lib). [junit4] 2> 1620503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] 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> 1620504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1620504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1620504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1620504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41284/solr [junit4] 2> 1620504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1620505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1620505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1620506 INFO (zkCallback-837-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5655873c name:ZooKeeperConnection Watcher:127.0.0.1:41284 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1620507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1620507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1620511 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1620512 INFO (zkCallback-838-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3eef601f name:ZooKeeperConnection Watcher:127.0.0.1:41284/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1620512 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1620522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1621527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54652_ [junit4] 2> 1621527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:54652_ [junit4] 2> 1621528 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] 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> 1621528 INFO (zkCallback-834-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> 1621529 INFO (zkCallback-838-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ ] 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> 1621530 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1621530 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94587523969384458-127.0.0.1:54652_-n_0000000001 [junit4] 2> 1621531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94587523969384454-127.0.0.1:51525_-n_0000000000 to know if I could be the leader [junit4] 2> 1621547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1621547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1621548 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] 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_48D1D9C482DB894E-001/shard-1-001/cores [junit4] 2> 1621549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] 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_48D1D9C482DB894E-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_48D1D9C482DB894E-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1621549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] 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_48D1D9C482DB894E-001/shard-1-001/cores/collection1/ [junit4] 2> 1621549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1621552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1621552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[48D1D9C482DB894E]) [n:127.0.0.1:54652_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1621555 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1621555 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1621557 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1621557 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:54652";, [junit4] 2> "node_name":"127.0.0.1:54652_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1621557 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] 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:54652";, [junit4] 2> "node_name":"127.0.0.1:54652_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1621558 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1621558 INFO (OverseerStateUpdate-94587523969384454-127.0.0.1:51525_-n_0000000000) [n:127.0.0.1:51525_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1621660 INFO (zkCallback-834-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: 2) [junit4] 2> 1621660 INFO (zkCallback-838-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1621660 INFO (zkCallback-832-thread-1-processing-n:127.0.0.1:51525_) [n:127.0.0.1:51525_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1622557 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1622557 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1622558 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1622558 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1622559 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1622559 INFO (coreLoadExecutor-3093-thread-1-processing-n:127.0.0.1:54652_) [n:127.0.0.1:54652_ c:collection1 x:collection1] [...truncated too long message...] 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> 4431882 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[48D1D9C482DB894E]-worker) [n:127.0.0.1:58864_ev_sff%2Ff c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Sep 26, 2015 2:58:10 PM 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): {other_tl1=PostingsFormat(name=Asserting), range_facet_l_dv=PostingsFormat(name=Direct), _version_=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=Asserting), a_i1=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Direct), range_facet_i_dv=PostingsFormat(name=LuceneVarGapFixedInterval), text=FST50, range_facet_l=PostingsFormat(name=LuceneVarGapFixedInterval), timestamp=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=iw, timezone=Antarctica/Macquarie [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=138366960,total=521142272 [junit4] 2> NOTE: All tests run in this JVM: [DistribDocExpirationUpdateProcessorTest, MoreLikeThisHandlerTest, TestStressUserVersions, ShardRoutingCustomTest, DistanceUnitsTest, SpellCheckComponentTest, TestCustomSort, DocValuesMissingTest, HdfsNNFailoverTest, TestConfigSets, TestBM25SimilarityFactory, BJQParserTest, TestCodecSupport, MultiTermTest, TestHashPartitioner, SliceStateTest, SpatialRPTFieldTypeTest, MinimalSchemaTest, SuggestComponentTest, TestQuerySenderListener, TestReplicationHandlerBackup, TriLevelCompositeIdRoutingTest, TestMaxScoreQueryParser, TestDistributedGrouping, TestOverriddenPrefixQueryForCustomFieldType, ZkSolrClientTest, TestCrossCoreJoin, ConvertedLegacyTest, UniqFieldsUpdateProcessorFactoryTest, TestMacros, ChaosMonkeySafeLeaderTest, TestJettySolrRunner, SolrCloudExampleTest, TestReloadDeadlock, TestScoreJoinQPScore, TestUpdate, TestRemoveLastDynamicCopyField, TestDFRSimilarityFactory, TestAnalyzeInfixSuggestions, CoreAdminRequestStatusTest, TestPartialUpdateDeduplication, TestFieldTypeResource, TestCloudPivotFacet, TestBlendedInfixSuggestions, ZkControllerTest, TestRestoreCore, TestLMJelinekMercerSimilarityFactory, RuleEngineTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SimplePostToolTest, AddSchemaFieldsUpdateProcessorFactoryTest, OpenCloseCoreStressTest, HdfsRecoveryZkTest, TestJsonFacets, SoftAutoCommitTest, TestFastOutputStream, MBeansHandlerTest, DocExpirationUpdateProcessorFactoryTest, TestJmxMonitoredMap, TestSchemaManager, BitVectorTest, TestDistribDocBasedVersion, CustomCollectionTest, TestDynamicLoading, TestShortCircuitedRequests, DistributedFacetPivotLongTailTest, TestSchemaNameResource, TestManagedSchemaFieldTypeResource, TestTolerantSearch, ShardRoutingTest, CollectionsAPIDistributedZkTest, TestMiniSolrCloudCluster, TestRuleBasedAuthorizationPlugin, AtomicUpdatesTest, TestNRTOpen, JavabinLoaderTest, CleanupOldIndexTest, LoggingHandlerTest, TestRTGBase, SpellPossibilityIteratorTest, SolrInfoMBeanTest, TestFiltering, TestRebalanceLeaders, TestConfigSetsAPIExclusivity, DocValuesTest, TestUtils, SolrCmdDistributorTest, TestAuthenticationFramework, TestSolrCoreProperties, TestMergePolicyConfig, TestSolrDeletionPolicy2, SampleTest, TestDocSet, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestFastWriter, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, DistributedIntervalFacetingTest, CdcrRequestHandlerTest, CollectionReloadTest, DeleteReplicaTest, DistribJoinFromCollectionTest, HttpPartitionTest, SharedFSAutoReplicaFailoverUtilsTest, SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestCollectionAPI, TestConfigSetsAPI, TestCryptoKeys, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestMiniSolrCloudClusterBase, TestRequestStatusCollectionAPI, VMParamsZkACLAndCredentialsProvidersTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [546/546] on J1 in 899.99s, 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: 48D1D9C482DB894E] Total time: 200 minutes 53 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
