Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/9/
1 tests failed. FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=6809, name=Thread-1835, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=6809, name=Thread-1835, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:45618/sx/n/collection1 at __randomizedtesting.SeedInfo.seed([24F57DCB9E44AF83]:0) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:644) Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:45618/sx/n/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:585) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166) at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:642) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:152) at java.net.SocketInputStream.read(SocketInputStream.java:122) 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:481) ... 5 more Build Log: [...truncated 11445 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/init-core-data-001 [junit4] 2> 491649 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[24F57DCB9E44AF83]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /sx/n [junit4] 2> 491653 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 491653 INFO (Thread-1644) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 491653 INFO (Thread-1644) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 491753 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkTestServer start zk server on port:38867 [junit4] 2> 491753 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 491754 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 491758 INFO (zkCallback-1664-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@738884c0 name:ZooKeeperConnection Watcher:127.0.0.1:38867 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 491758 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 491758 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 491758 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 491761 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 491762 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 491765 INFO (zkCallback-1665-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a94040 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 491766 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 491766 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 491766 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 491768 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 491769 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 491770 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 491771 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 491771 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 491773 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 491773 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 491775 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 491775 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 491776 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 491776 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 491778 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 491778 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 491779 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 491779 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 491780 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 491780 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 491781 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 491782 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 491783 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 491783 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 491784 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 491784 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 491785 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 491786 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 491881 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1 [junit4] 2> 491882 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 491884 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31d59937{/sx/n,null,AVAILABLE} [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5953cdfc{HTTP/1.1}{127.0.0.1:45618} [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.Server Started @494713ms [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=45618, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/tempDir-001/control/data, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores, hostContext=/sx/n} [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001' [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 491885 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 491886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 491886 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 491887 INFO (zkCallback-1666-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66990310 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 491887 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 491888 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 491889 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 491889 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/solr.xml [junit4] 2> 491904 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores [junit4] 2> 491905 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer New CoreContainer 1802670036 [junit4] 2> 491905 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001] [junit4] 2> 491905 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/lib [junit4] 2> 491905 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] 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> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38867/solr [junit4] 2> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 491907 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 491908 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 491909 INFO (zkCallback-1669-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d8e2320 name:ZooKeeperConnection Watcher:127.0.0.1:38867 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 491909 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 491910 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 491916 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 491916 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@44b90d93 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 491916 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 491918 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 491920 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 491922 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 491924 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 491926 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 491928 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 491929 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 491930 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 491931 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 491932 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45618_sx%2Fn [junit4] 2> 491933 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45618_sx%2Fn [junit4] 2> 491934 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 491935 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 491936 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 491937 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000 [junit4] 2> 491937 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45618_sx%2Fn [junit4] 2> 491937 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 491938 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer Overseer (id=95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) starting [junit4] 2> 491940 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 491945 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 491946 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 491946 INFO (OverseerCollectionConfigSetProcessor-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 491946 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 491959 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 491959 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 491960 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores [junit4] 2> 491961 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {loadOnStartup=true, dataDir=data/, config=solrconfig.xml, collection=control_collection, coreNodeName=, name=collection1, schema=schema.xml, shard=, transient=false, configSetProperties=configsetprops.json} [junit4] 2> 491961 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1 [junit4] 2> 491961 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 491962 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 491962 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 491964 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2 [junit4] 2> 491964 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 491964 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 491965 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:45618_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:45618/sx/n"} current state version: 0 [junit4] 2> 491965 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:45618_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:45618/sx/n"} [junit4] 2> 491966 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 491966 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 491968 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] 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> 492965 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 492965 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 492966 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 492966 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 492967 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 492967 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1' [junit4] 2> 492967 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 492967 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 492969 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 492975 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 492989 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0 [junit4] 2> 493028 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 493035 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 493234 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 493236 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 493237 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 493240 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 493258 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2 [junit4] 2> 493258 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 493258 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 493258 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/] [junit4] 2> 493258 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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@3f74020f [junit4] 2> 493259 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data [junit4] 2> 493259 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/index/ [junit4] 2> 493259 WARN (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 493259 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/index [junit4] 2> 493259 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4670968397486721] [junit4] 2> 493260 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7e0b6883 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1efab11b),segFN=segments_1,generation=1} [junit4] 2> 493260 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 493260 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45618_sx%2Fn 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-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/ [junit4] 2> 493261 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493264 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493265 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493265 WARN (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 493267 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 493268 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 493270 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 493271 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 493281 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/update/json/docs,/admin/properties,standard,/update/csv,/get,/dump,/admin/threads,/admin/luke,/admin/ping,/admin/system,/admin/file,/update,/replication,/admin/logging,/schema,/config,/admin/mbeans,/update/json,/admin/plugins [junit4] 2> 493283 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493283 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493283 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493284 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 493284 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 493285 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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@35a27961 [junit4] 2> 493285 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7e0b6883 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1efab11b),segFN=segments_1,generation=1} [junit4] 2> 493285 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 493285 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4e86f1c2[collection1] main [junit4] 2> 493285 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493287 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493287 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493287 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 493287 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 493287 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493288 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493288 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493288 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 493288 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 493289 INFO (searcherExecutor-2542-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e86f1c2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 493289 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493289 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493289 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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> 493289 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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 1526895544815845376 [junit4] 2> 493291 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn 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 1526895544815845376 [junit4] 2> 493291 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 493292 INFO (coreLoadExecutor-2541-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 493292 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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:45618/sx/n collection:control_collection shard:shard1 [junit4] 2> 493293 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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> 493296 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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/95430971443970052-core_node1-n_0000000000 [junit4] 2> 493296 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=10000 [junit4] 2> 493298 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 493298 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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> 493298 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45618/sx/n/collection1/ [junit4] 2> 493298 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 493298 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:45618/sx/n/collection1/ has no replicas [junit4] 2> 493298 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] 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> 493299 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 493305 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 493307 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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:45618/sx/n/collection1/ shard1 [junit4] 2> 493308 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] 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:45618/sx/n";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 493418 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] 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> 493458 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:45618/sx/n/collection1/ and leader is http://127.0.0.1:45618/sx/n/collection1/ [junit4] 2> 493458 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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:45618/sx/n [junit4] 2> 493458 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 493458 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 493458 INFO (coreZkRegister-2535-thread-1-processing-n:127.0.0.1:45618_sx%2Fn x:collection1 c:control_collection s:shard1 r:core_node1) [n:127.0.0.1:45618_sx%2Fn 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> 493460 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:45618_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:45618/sx/n"} current state version: 2 [junit4] 2> 493461 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:45618_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:45618/sx/n"} [junit4] 2> 493465 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493466 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493469 INFO (zkCallback-1672-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6e5c65f8 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493469 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493469 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493469 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 493472 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 493563 INFO (zkCallback-1672-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> 493565 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] 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> 493583 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1 [junit4] 2> 493584 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001 [junit4] 2> 493584 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 493593 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@490e9fbe{/sx/n,null,AVAILABLE} [junit4] 2> 493593 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.ServerConnector Started ServerConnector@96587ed{HTTP/1.1}{127.0.0.1:59235} [junit4] 2> 493593 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.e.j.s.Server Started @496421ms [junit4] 2> 493593 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=59235, solrconfig=solrconfig.xml, hostContext=/sx/n, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/tempDir-001/jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores} [junit4] 2> 493594 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 493594 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001' [junit4] 2> 493594 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 493594 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 493594 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493601 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493603 INFO (zkCallback-1673-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3338e848 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493604 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493610 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 493610 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/solr.xml [junit4] 2> 493624 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores [junit4] 2> 493625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer New CoreContainer 119013076 [junit4] 2> 493625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001] [junit4] 2> 493625 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/lib [junit4] 2> 493625 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] 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> 493626 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38867/solr [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 493627 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493629 INFO (zkCallback-1676-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2eed27ad name:ZooKeeperConnection Watcher:127.0.0.1:38867 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493629 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493630 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 493632 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 493633 INFO (zkCallback-1677-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cb08ad5 name:ZooKeeperConnection Watcher:127.0.0.1:38867/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 493633 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 493639 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 494648 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59235_sx%2Fn [junit4] 2> 494649 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59235_sx%2Fn [junit4] 2> 494650 INFO (zkCallback-1672-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> 494650 INFO (zkCallback-1677-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn ] 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> 494650 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] 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> 494652 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 494652 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95430971443970056-127.0.0.1:59235_sx%2Fn-n_0000000001 [junit4] 2> 494653 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000 to know if I could be the leader [junit4] 2> 494680 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 494681 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 494681 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores [junit4] 2> 494697 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {configSetProperties=configsetprops.json, dataDir=data/, name=collection1, loadOnStartup=true, schema=schema.xml, coreNodeName=, collection=collection1, shard=, config=solrconfig.xml, transient=false} [junit4] 2> 494697 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1 [junit4] 2> 494697 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 494698 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2 [junit4] 2> 494698 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[24F57DCB9E44AF83]) [n:127.0.0.1:59235_sx%2Fn ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 494704 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 494704 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 494711 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:59235_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:59235/sx/n"} current state version: 3 [junit4] 2> 494712 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ReplicaMutator Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:59235_sx%2Fn", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:59235/sx/n"} [junit4] 2> 494712 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 494712 INFO (OverseerStateUpdate-95430971443970052-127.0.0.1:45618_sx%2Fn-n_0000000000) [n:127.0.0.1:45618_sx%2Fn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 494717 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 494815 INFO (zkCallback-1677-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn ] 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> 494815 INFO (zkCallback-1670-thread-1-processing-n:127.0.0.1:45618_sx%2Fn) [n:127.0.0.1:45618_sx%2Fn ] 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> 494818 INFO (zkCallback-1672-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> 495717 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 495717 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 495718 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 495718 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 495718 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 495718 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1' [junit4] 2> 495719 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 495719 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 495720 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 495727 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 495739 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0 [junit4] 2> 495768 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 495775 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 495974 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 495975 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 495976 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 495979 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 496002 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn 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-5.5/solr/build/solr-core/test/J2 [junit4] 2> 496002 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 496002 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 496002 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/] [junit4] 2> 496003 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3f74020f [junit4] 2> 496003 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data [junit4] 2> 496003 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 496003 WARN (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 496003 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/index [junit4] 2> 496004 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4670968397486721] [junit4] 2> 496004 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2d8e4041 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@561c82c4),segFN=segments_1,generation=1} [junit4] 2> 496004 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 496005 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 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-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/ [junit4] 2> 496005 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001/shard-1-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 496008 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 496009 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 496009 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 496009 INFO (coreLoadExecutor-2552-thread-1-processing-n:127.0.0.1:59235_sx%2Fn) [n:127.0.0.1:59235_sx%2Fn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] [...truncated too long message...] lr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:642) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:152) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:481) [junit4] > ... 5 moreThrowable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=6810, name=Thread-1836, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] [junit4] > Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:45618/sx/n/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([24F57DCB9E44AF83]:0) [junit4] > at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:644) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:45618/sx/n/collection1 [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:585) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:166) [junit4] > at org.apache.solr.cloud.FullSolrCloudDistribCmdsTest$1IndexThread.run(FullSolrCloudDistribCmdsTest.java:642) [junit4] > Caused by: java.net.SocketTimeoutException: Read timed out [junit4] > at java.net.SocketInputStream.socketRead0(Native Method) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:152) [junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] > at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] > at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:481) [junit4] > ... 5 more [junit4] 2> 834002 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[24F57DCB9E44AF83]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_24F57DCB9E44AF83-001 [junit4] 2> Feb 22, 2016 5:16:45 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Lucene54, sim=DefaultSimilarity, locale=uk-UA, timezone=Europe/Vilnius [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=180756008,total=515899392 [junit4] 2> NOTE: All tests run in this JVM: [TestSweetSpotSimilarityFactory, BlockJoinFacetSimpleTest, TestSortingResponseWriter, TestSchemaVersionResource, TestPerFieldSimilarityWithDefaultOverride, HighlighterMaxOffsetTest, RecoveryZkTest, CircularListTest, TestClassicSimilarityFactory, QueryParsingTest, TestCoreDiscovery, DistributedFacetPivotSmallAdvancedTest, DataDrivenBlockJoinTest, TestFastWriter, DirectUpdateHandlerOptimizeTest, TestSearcherReuse, CollectionReloadTest, FacetPivotSmallTest, TestUtils, RuleEngineTest, SolrTestCaseJ4Test, SliceStateTest, TestElisionMultitermQuery, TestDynamicFieldResource, TestNamedUpdateProcessors, HighlighterConfigTest, TestLRUStatsCache, DistribJoinFromCollectionTest, TestDistribIDF, TestSolrConfigHandler, DistributedExpandComponentTest, TestSolrQueryParser, TestSha256AuthenticationProvider, TestExclusionRuleCollectionAccess, SuggesterWFSTTest, TestImplicitCoreProperties, TestRawResponseWriter, TestExtendedDismaxParser, RegexBoostProcessorTest, DateRangeFieldTest, TestJsonRequest, MultiThreadedOCPTest, BitVectorTest, DateMathParserTest, TestIndexingPerformance, SimpleFacetsTest, TestSolrConfigHandlerCloud, DeleteLastCustomShardedReplicaTest, TestPivotHelperCode, TestRestManager, HLLSerializationTest, TestMinMaxOnMultiValuedField, DebugComponentTest, TestIndexSearcher, TestSolrDeletionPolicy1, RequestHandlersTest, FullSolrCloudDistribCmdsTest] [junit4] Completed [132/566 (1!)] on J2 in 342.88s, 1 test, 1 error <<< FAILURES! [...truncated 1394 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 1 error, 34 ignored (18 assumptions) [seed: 24F57DCB9E44AF83] Total time: 196 minutes 14 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
