Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/778/
3 tests failed. REGRESSION: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=60554, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=60554, name=collection0, 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:57002/yaw/lh, http://127.0.0.1:36512/yaw/lh, http://127.0.0.1:35194/yaw/lh, http://127.0.0.1:53327/yaw/lh, http://127.0.0.1:39652/yaw/lh] at __randomizedtesting.SeedInfo.seed([EC5A42777941B909]: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:57002/yaw/lh, http://127.0.0.1:36512/yaw/lh, http://127.0.0.1:35194/yaw/lh, http://127.0.0.1:53327/yaw/lh, http://127.0.0.1:39652/yaw/lh] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) 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.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39652/yaw/lh: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qn- at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) ... 5 more REGRESSION: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=701, name=Thread-242, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=701, name=Thread-242, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] at __randomizedtesting.SeedInfo.seed([EC5A42777941B909:640E7DADD7BDD4F1]:0) Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54876/xb_drl/wv/collection1 at __randomizedtesting.SeedInfo.seed([EC5A42777941B909]:0) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:645) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:54876/xb_drl/wv/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:152) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:643) 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:465) ... 5 more FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=10314, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=10314, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:36703: Could not find collection : awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([EC5A42777941B909]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1086) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:857) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:800) 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 9820 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/init-core-data-001 [junit4] 2> 57735 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[EC5A42777941B909]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xb_drl/wv [junit4] 2> 57742 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 57742 INFO (Thread-72) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 57743 INFO (Thread-72) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 57843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkTestServer start zk server on port:34989 [junit4] 2> 57844 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 57845 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 57860 INFO (zkCallback-85-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@59718365 name:ZooKeeperConnection Watcher:127.0.0.1:34989 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 57860 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 57861 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 57861 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 57872 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 57881 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 57886 INFO (zkCallback-86-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@75c3b4cb name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 57886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 57886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 57887 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 57889 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 57890 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 57893 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 57895 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57895 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 57897 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 57897 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 57899 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57899 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 57901 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57901 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 57902 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57903 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 57904 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57904 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 57906 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57906 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 57908 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57908 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 57909 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57910 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 57911 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57911 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 57938 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 57938 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 58364 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1 [junit4] 2> 58366 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 58369 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3e109bcf{/xb_drl/wv,null,AVAILABLE} [junit4] 2> 58375 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.ServerConnector Started ServerConnector@52fff279{HTTP/1.1}{127.0.0.1:54876} [junit4] 2> 58375 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.Server Started @61536ms [junit4] 2> 58375 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/tempDir-001/control/data, hostContext=/xb_drl/wv, hostPort=54876, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores} [junit4] 2> 58376 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 58376 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/' [junit4] 2> 58394 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 58395 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 58399 INFO (zkCallback-87-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@910e05d name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 58399 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 58400 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 58402 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 58403 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/solr.xml [junit4] 2> 58417 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores [junit4] 2> 58417 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer New CoreContainer 321662108 [junit4] 2> 58417 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/] [junit4] 2> 58417 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/lib [junit4] 2> 58417 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/lib). [junit4] 2> 58429 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 58431 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 58431 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 58431 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 58432 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34989/solr [junit4] 2> 58432 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 58432 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 58433 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 58445 INFO (zkCallback-89-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66e0de09 name:ZooKeeperConnection Watcher:127.0.0.1:34989 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 58445 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 58446 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 58453 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 58456 INFO (zkCallback-90-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2b5c6fd9 name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 58456 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 58458 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 58460 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 58464 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 58470 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 58471 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 58474 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 58483 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 58484 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 58485 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 58486 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54876_xb_drl%2Fwv [junit4] 2> 58487 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:54876_xb_drl%2Fwv [junit4] 2> 58489 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 58491 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 58492 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 58494 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000 [junit4] 2> 58495 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:54876_xb_drl%2Fwv [junit4] 2> 58495 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 58496 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.Overseer Overseer (id=94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) starting [junit4] 2> 58498 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 58507 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 58518 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 58518 INFO (OverseerCollectionConfigSetProcessor-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 58518 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 58522 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 58579 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 58579 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 58580 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores [junit4] 2> 58581 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 58581 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/ [junit4] 2> 58581 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 58631 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 58632 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 58640 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 58640 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 58643 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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:54876/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:54876_xb_drl%2Fwv", [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> 58644 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 58644 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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:54876/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:54876_xb_drl%2Fwv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 58644 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 58646 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 58648 INFO (zkCallback-90-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 58658 INFO (zkCallback-90-thread-2-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 59644 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 59645 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 59646 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 59646 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 59647 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 59648 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/' [junit4] 2> 59669 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 59676 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 59684 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 59695 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 59697 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 59703 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 59803 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 59804 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 59806 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 59809 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 59830 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2 [junit4] 2> 59830 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 59830 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 59831 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 59831 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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@628d27c5 [junit4] 2> 59832 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data [junit4] 2> 59832 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data/index/ [junit4] 2> 59832 WARN (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 59833 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data/index [junit4] 2> 59833 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@674e0f9a [junit4] 2> 59834 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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@524df2c1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6559595a; maxCacheMB=0.7421875 maxMergeSizeMB=0.7197265625)),segFN=segments_1,generation=1} [junit4] 2> 59834 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 59834 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data/ [junit4] 2> 59835 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:54876_xb_drl%2Fwv 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 59850 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 59860 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 59860 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 59860 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 59860 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 59861 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59861 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59861 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59861 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59863 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59865 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59866 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59868 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 59876 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59877 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59877 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59878 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59879 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 59879 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 59879 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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=26, maxMergeAtOnceExplicit=49, maxMergedSegmentMB=72.404296875, floorSegmentMB=1.3984375, forceMergeDeletesPctAllowed=4.3014731417859, segmentsPerTier=43.0, maxCFSSegmentSizeMB=0.2958984375, noCFSRatio=0.3962090223910433 [junit4] 2> 59880 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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@524df2c1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6559595a; maxCacheMB=0.7421875 maxMergeSizeMB=0.7197265625)),segFN=segments_1,generation=1} [junit4] 2> 59880 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 59881 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2991157e[collection1] main [junit4] 2> 59881 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59882 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59882 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59883 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 59883 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 59883 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59883 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59883 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59884 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 59884 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 59884 INFO (searcherExecutor-125-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2991157e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 59885 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59885 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59885 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59885 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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 1510762929440423936 [junit4] 2> 59887 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv 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 1510762929440423936 [junit4] 2> 59888 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 59888 INFO (coreLoadExecutor-124-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 59889 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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:54876/xb_drl/wv collection:control_collection shard:shard1 [junit4] 2> 59890 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59900 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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/94422682949713924-core_node1-n_0000000000 [junit4] 2> 59902 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 59905 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 59905 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 59906 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:54876/xb_drl/wv/collection1/ [junit4] 2> 59906 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 59906 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 59906 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:54876/xb_drl/wv/collection1/ has no replicas [junit4] 2> 59906 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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:54876/xb_drl/wv/collection1/ shard1 [junit4] 2> 59906 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 59912 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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:54876/xb_drl/wv";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 60016 INFO (zkCallback-90-thread-2-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 60016 INFO (zkCallback-90-thread-3-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 60063 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:54876/xb_drl/wv/collection1/ and leader is http://127.0.0.1:54876/xb_drl/wv/collection1/ [junit4] 2> 60063 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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:54876/xb_drl/wv [junit4] 2> 60063 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 60063 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 60063 INFO (coreZkRegister-118-thread-1-processing-n:127.0.0.1:54876_xb_drl%2Fwv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54876_xb_drl%2Fwv 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> 60066 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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:54876/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:54876_xb_drl%2Fwv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 60067 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54876/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:54876_xb_drl%2Fwv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 60144 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 60144 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60147 INFO (zkCallback-92-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3804f840 name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60148 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60148 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 60148 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 60154 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 60171 INFO (zkCallback-90-thread-3-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 60171 INFO (zkCallback-90-thread-2-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 60171 INFO (zkCallback-92-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> 60584 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores/collection1 [junit4] 2> 60587 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001 [junit4] 2> 60588 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 60590 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5be1fe7b{/xb_drl/wv,null,AVAILABLE} [junit4] 2> 60591 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7c232b27{HTTP/1.1}{127.0.0.1:43208} [junit4] 2> 60591 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.e.j.s.Server Started @63752ms [junit4] 2> 60592 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/xb_drl/wv, hostPort=43208, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores} [junit4] 2> 60592 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 60592 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/' [junit4] 2> 60610 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 60611 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60614 INFO (zkCallback-93-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@157d5550 name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60614 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60616 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 60618 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 60618 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/solr.xml [junit4] 2> 60625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores [junit4] 2> 60625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer New CoreContainer 1653865118 [junit4] 2> 60625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/] [junit4] 2> 60625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/lib [junit4] 2> 60625 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/lib). [junit4] 2> 60634 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] 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> 60636 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 60636 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 60637 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 60637 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34989/solr [junit4] 2> 60637 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 60637 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 60639 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60643 INFO (zkCallback-95-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f812f3f name:ZooKeeperConnection Watcher:127.0.0.1:34989 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60644 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60644 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 60647 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 60651 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10d46de5 name:ZooKeeperConnection Watcher:127.0.0.1:34989/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 60651 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 60658 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 61665 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43208_xb_drl%2Fwv [junit4] 2> 61666 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:43208_xb_drl%2Fwv [junit4] 2> 61668 INFO (zkCallback-92-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> 61668 INFO (zkCallback-90-thread-3-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 61668 INFO (zkCallback-90-thread-2-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 61668 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv ] 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> 61670 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 61671 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94422682949713928-127.0.0.1:43208_xb_drl%2Fwv-n_0000000001 [junit4] 2> 61672 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000 to know if I could be the leader [junit4] 2> 61692 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 61692 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 61694 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores [junit4] 2> 61695 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] 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/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 61695 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores/collection1/ [junit4] 2> 61695 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 61696 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 61697 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[EC5A42777941B909]) [n:127.0.0.1:43208_xb_drl%2Fwv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 61698 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 61698 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 61700 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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:43208/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:43208_xb_drl%2Fwv", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 61701 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ReplicaMutator Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:43208/xb_drl/wv";, [junit4] 2> "node_name":"127.0.0.1:43208_xb_drl%2Fwv", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 61701 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 61701 INFO (OverseerStateUpdate-94422682949713924-127.0.0.1:54876_xb_drl%2Fwv-n_0000000000) [n:127.0.0.1:54876_xb_drl%2Fwv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 61702 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 61703 INFO (zkCallback-92-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> 61703 INFO (zkCallback-90-thread-2-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 61703 INFO (zkCallback-90-thread-3-processing-n:127.0.0.1:54876_xb_drl%2Fwv) [n:127.0.0.1:54876_xb_drl%2Fwv ] 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> 61703 INFO (zkCallback-96-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv ] 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> 62704 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 62704 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 62705 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 62705 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 62706 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 62707 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_EC5A42777941B909-001/shard-1-001/cores/collection1/' [junit4] 2> 62723 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 62729 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 62746 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 62756 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 62757 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 62763 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 62846 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 62847 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 62848 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 62851 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 62869 INFO (coreLoadExecutor-135-thread-1-processing-n:127.0.0.1:43208_xb_drl%2Fwv) [n:127.0.0.1:43208_xb_drl%2Fwv c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-tru [...truncated too long message...] GeneratedMethodAccessor94.invoke(Unknown Source) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111) [junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 3521553 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[EC5A42777941B909]-worker) [n:127.0.0.1:36703_ c:addReplicaColl s:shard1 r:core_node4 x:addReplicaColl_shard1_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Aug 28, 2015 12:27:46 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=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=22349, maxDocsPerChunk=666, blockSize=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=22349, blockSize=9)), sim=DefaultSimilarity, locale=es_GT, timezone=America/Iqaluit [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=208139648,total=531103744 [junit4] 2> NOTE: All tests run in this JVM: [ClusterStateTest, TestSolrCLIRunExample, BlockCacheTest, BigEndianAscendingWordDeserializerTest, TestSolr4Spatial2, SharedFSAutoReplicaFailoverUtilsTest, UUIDFieldTest, TestHighFrequencyDictionaryFactory, TestXIncludeConfig, StatsComponentTest, TestCSVResponseWriter, BadIndexSchemaTest, FullSolrCloudDistribCmdsTest, TestFieldTypeCollectionResource, QueryParsingTest, DirectUpdateHandlerTest, TestManagedStopFilterFactory, SegmentsInfoRequestHandlerTest, SystemInfoHandlerTest, XmlUpdateRequestHandlerTest, SparseHLLTest, TermVectorComponentDistributedTest, BinaryUpdateRequestHandlerTest, TestSweetSpotSimilarityFactory, DebugComponentTest, TestSort, HdfsThreadLeakTest, TestTrieFacet, TestAddFieldRealTimeGet, TestNamedUpdateProcessors, TestExactSharedStatsCache, RegexBoostProcessorTest, TestImplicitCoreProperties, HdfsSyncSliceTest, TestCursorMarkWithoutUniqueKey, SharedFSAutoReplicaFailoverTest, RecoveryAfterSoftCommitTest, TestManagedSchemaFieldTypeResource, TestManagedResource, TestCollationField, TestSearcherReuse, SampleTest, TestCollapseQParserPlugin, OverseerTaskQueueTest, DeleteReplicaTest, SecurityConfHandlerTest, TestClassNameShortening, MigrateRouteKeyTest, TestCoreDiscovery, AssignTest, RequestHandlersTest, TestManagedSynonymFilterFactory, SuggesterTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [253/543] on J2 in 2376.71s, 1 test, 1 error <<< FAILURES! [...truncated 918 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: 543 suites, 2145 tests, 3 errors, 29 ignored (17 assumptions) Total time: 201 minutes 28 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]
