Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/79/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=43325, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=43325, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47057: collection already exists: awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([36E19CFDDC88E5BE]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) FAILED: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest.test Error Message: Captured an uncaught exception in thread: Thread[id=108667, name=Thread-7641, state=RUNNABLE, group=TGRP-FullSolrCloudDistribCmdsTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=108667, name=Thread-7641, 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:36119/collection1 at __randomizedtesting.SeedInfo.seed([36E19CFDDC88E5BE]: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:36119/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:601) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) 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.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:495) ... 5 more Build Log: [...truncated 11979 lines...] [junit4] Suite: org.apache.solr.cloud.FullSolrCloudDistribCmdsTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/init-core-data-001 [junit4] 2> 1861447 INFO (SUITE-FullSolrCloudDistribCmdsTest-seed#[36E19CFDDC88E5BE]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1861450 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1861450 INFO (Thread-7467) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1861450 INFO (Thread-7467) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1861550 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkTestServer start zk server on port:55532 [junit4] 2> 1861550 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1861560 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1861582 INFO (zkCallback-27858-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e2c730b name:ZooKeeperConnection Watcher:127.0.0.1:55532 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1861583 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1861583 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1861583 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1861587 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1861588 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15507e9f22b0000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1861590 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1861594 INFO (zkCallback-27859-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@773e41c2 name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1861595 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1861595 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1861595 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1861596 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1861599 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1861600 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1861600 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1861600 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1861602 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 1861602 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1861603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1861603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1861603 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1861604 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1861605 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1861605 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1861606 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1861606 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1861607 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1861607 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1861610 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1861610 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1861613 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1861613 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1861615 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1861615 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1861616 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1861616 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1861827 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1 [junit4] 2> 1861831 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1861842 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@25545a63{/,null,AVAILABLE} [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5c04d22e{HTTP/1.1,[http/1.1]}{127.0.0.1:36119} [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.Server Started @1864894ms [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/tempDir-001/control/data, hostContext=/, hostPort=36119, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores} [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001' [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1861843 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1861849 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1861855 INFO (zkCallback-27860-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@535c4681 name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1861855 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1861855 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1861863 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1861863 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/solr.xml [junit4] 2> 1861870 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores [junit4] 2> 1861870 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer New CoreContainer 681006555 [junit4] 2> 1861870 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001] [junit4] 2> 1861870 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/lib [junit4] 2> 1861871 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] 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> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55532/solr [junit4] 2> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1861874 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1861878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1861892 INFO (zkCallback-27863-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@72b35674 name:ZooKeeperConnection Watcher:127.0.0.1:55532 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1861895 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1861895 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1861896 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/1660808019@543aed41 [junit4] 2> 1861899 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1861899 INFO (zkCallback-27864-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16977581 name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1861899 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1861900 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1861904 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1861906 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1861909 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1861909 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1861910 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1861911 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1861912 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1861912 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1861912 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1861913 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1861913 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1861919 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1861923 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1861925 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1861925 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95991668430012420-127.0.0.1:36119_-n_0000000000 [junit4] 2> 1861926 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36119_ [junit4] 2> 1861926 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1861929 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer Overseer (id=95991668430012420-127.0.0.1:36119_-n_0000000000) starting [junit4] 2> 1861930 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1861937 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1861947 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1861962 INFO (OverseerCollectionConfigSetProcessor-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1861971 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36119_ [junit4] 2> 1861972 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1861972 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:36119_ [junit4] 2> 1861974 INFO (zkCallback-27864-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1861975 INFO (zkCallback-27864-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1861983 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1861983 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1861983 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores [junit4] 2> 1861984 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1861984 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1 [junit4] 2> 1861984 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1861985 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1861985 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1861988 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1861988 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:36119";, [junit4] 2> "node_name":"127.0.0.1:36119_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1861988 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:36119";, [junit4] 2> "node_name":"127.0.0.1:36119_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1861988 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:36119_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1861988 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 1861989 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1861990 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1861990 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1861992 INFO (zkCallback-27864-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1861992 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1861992 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1861993 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1861994 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1861994 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1861996 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1861996 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1' [junit4] 2> 1861996 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1861996 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1861997 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1862004 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1862008 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 1862017 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1862021 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1862168 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1862169 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1862169 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1862171 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1862216 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1862216 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1862216 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1862216 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/] [junit4] 2> 1862216 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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@7ede6d61 [junit4] 2> 1862217 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data [junit4] 2> 1862217 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/index/ [junit4] 2> 1862217 WARN (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1862217 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/index [junit4] 2> 1862218 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=4, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1862218 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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@60f5d5e9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21dcff4f),segFN=segments_1,generation=1} [junit4] 2> 1862356 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1862360 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862361 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862362 WARN (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862363 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36119_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/ [junit4] 2> 1862363 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1862365 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1862366 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1862367 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1862368 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1862371 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 1862371 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862372 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862372 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862372 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1862372 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1862373 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=0.0087890625, floorSegmentMB=1.134765625, forceMergeDeletesPctAllowed=13.973770654377756, segmentsPerTier=2.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 1862373 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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@60f5d5e9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@21dcff4f),segFN=segments_1,generation=1} [junit4] 2> 1862373 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1862374 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4f778460[collection1] main] [junit4] 2> 1862374 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862374 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862375 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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> 1862376 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ 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 1535866695849082880 [junit4] 2> 1862380 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 4.0ms to seed version buckets with highest version 1535866695849082880 [junit4] 2> 1862380 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1862381 INFO (coreLoadExecutor-8386-thread-1-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1862384 INFO (searcherExecutor-8387-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f778460[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1862384 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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:36119 collection:control_collection shard:shard1 [junit4] 2> 1862385 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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> 1862387 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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/95991668430012420-core_node1-n_0000000000 [junit4] 2> 1862387 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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> 1862388 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1862388 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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> 1862388 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36119/collection1/ [junit4] 2> 1862388 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1862388 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:36119/collection1/ has no replicas [junit4] 2> 1862389 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1862391 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95991668430012420-core_node1-n_0000000000 [junit4] 2> 1862392 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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:36119/collection1/ shard1 [junit4] 2> 1862394 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 1862395 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:36119";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1862489 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:55532/solr [junit4] 2> 1862489 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1862493 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1862508 INFO (zkCallback-27868-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1202a981 name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1862508 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1862509 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1862509 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1862508 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1862516 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1862525 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1862536 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1862544 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:36119/collection1/ and leader is http://127.0.0.1:36119/collection1/ [junit4] 2> 1862544 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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:36119 [junit4] 2> 1862544 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1862544 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1862544 INFO (coreZkRegister-8380-thread-1-processing-n:127.0.0.1:36119_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:36119_ 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> 1862565 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:36119";, [junit4] 2> "node_name":"127.0.0.1:36119_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1862565 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ReplicaMutator Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:36119";, [junit4] 2> "node_name":"127.0.0.1:36119_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1862661 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores/collection1 [junit4] 2> 1862662 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001 [junit4] 2> 1862663 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1862667 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1862668 INFO (zkCallback-27868-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> 1862675 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c9526b9{/,null,AVAILABLE} [junit4] 2> 1862676 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.ServerConnector Started ServerConnector@229d47ce{HTTP/1.1,[http/1.1]}{127.0.0.1:45834} [junit4] 2> 1862676 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.e.j.s.Server Started @1865728ms [junit4] 2> 1862676 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=45834, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores} [junit4] 2> 1862676 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1862676 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001' [junit4] 2> 1862693 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1862693 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1862694 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1862698 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1862705 INFO (zkCallback-27869-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6b26fe40 name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1862711 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1862711 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1862717 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1862718 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/solr.xml [junit4] 2> 1862872 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores [junit4] 2> 1862872 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer New CoreContainer 431997663 [junit4] 2> 1862872 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001] [junit4] 2> 1862875 WARN (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/lib [junit4] 2> 1862875 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] 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> 1862877 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1862878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1862878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1862878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55532/solr [junit4] 2> 1862878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1862878 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1862905 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1862912 INFO (zkCallback-27872-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7da0ffa4 name:ZooKeeperConnection Watcher:127.0.0.1:55532 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1862913 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1862914 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1862914 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$42/1660808019@7a9dee4a [junit4] 2> 1862953 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1862954 INFO (zkCallback-27873-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5071cc7a name:ZooKeeperConnection Watcher:127.0.0.1:55532/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1862954 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1862969 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1862969 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 1862974 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1862977 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1862977 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95991668430012424-127.0.0.1:45834_-n_0000000001 [junit4] 2> 1862977 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95991668430012420-127.0.0.1:36119_-n_0000000000 to know if I could be the leader [junit4] 2> 1862977 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.ZkController Publish node=127.0.0.1:45834_ as DOWN [junit4] 2> 1862978 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:45834_"} current state version: 3 [junit4] 2> 1862978 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45834_ [junit4] 2> 1863081 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1863081 INFO (zkCallback-27868-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> 1863081 INFO (zkCallback-27873-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ ] 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> 1863979 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45834_ [junit4] 2> 1863979 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45834_ [junit4] 2> 1863981 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1863981 INFO (zkCallback-27868-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> 1863981 INFO (zkCallback-27868-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1863981 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1863981 INFO (zkCallback-27873-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ ] 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> 1863988 INFO (zkCallback-27873-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1863996 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1863996 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1863999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores [junit4] 2> 1863999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1863999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores/collection1 [junit4] 2> 1863999 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1864002 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1864002 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1864003 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45834";, [junit4] 2> "node_name":"127.0.0.1:45834_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1864003 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ReplicaMutator Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45834";, [junit4] 2> "node_name":"127.0.0.1:45834_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1864003 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1864003 INFO (OverseerStateUpdate-95991668430012420-127.0.0.1:36119_-n_0000000000) [n:127.0.0.1:36119_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1864005 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 1864006 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1] [junit4] 2> 1864007 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1864007 INFO (TEST-FullSolrCloudDistribCmdsTest.test-seed#[36E19CFDDC88E5BE]) [n:127.0.0.1:45834_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1864009 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1864105 INFO (zkCallback-27864-thread-2-processing-n:127.0.0.1:36119_) [n:127.0.0.1:36119_ ] 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> 1864105 INFO (zkCallback-27873-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ ] 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> 1864105 INFO (zkCallback-27868-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> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores/collection1' [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1865010 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1865011 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1865015 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1865018 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 1865028 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1865032 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1865170 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1865188 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1865188 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1865193 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1865213 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ 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-6.x/solr/build/solr-core/test/J2 [junit4] 2> 1865213 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1865213 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1865213 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.FullSolrCloudDistribCmdsTest_36E19CFDDC88E5BE-001/shard-1-001/cores/collection1/data/] [junit4] 2> 1865213 INFO (coreLoadExecutor-8397-thread-1-processing-n:127.0.0.1:45834_) [n:127.0.0.1:45834_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap [...truncated too long message...] it4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47057: collection already exists: awholynewstresscollection_collection4_0 [junit4] > at __randomizedtesting.SeedInfo.seed([36E19CFDDC88E5BE]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=43323, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47057: collection already exists: awholynewstresscollection_collection3_0 [junit4] > at __randomizedtesting.SeedInfo.seed([36E19CFDDC88E5BE]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987)Throwable #7: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=43320, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47057: collection already exists: awholynewstresscollection_collection0_1 [junit4] > at __randomizedtesting.SeedInfo.seed([36E19CFDDC88E5BE]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1228) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:998) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:934) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1620) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) [junit4] 2> 3097970 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[36E19CFDDC88E5BE]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_36E19CFDDC88E5BE-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60), sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=nl, timezone=America/North_Dakota/New_Salem [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=175979840,total=479199232 [junit4] 2> NOTE: All tests run in this JVM: [SolrIndexSplitterTest, TestMacros, BJQParserTest, UUIDUpdateProcessorFallbackTest, SchemaVersionSpecificBehaviorTest, TestDFRSimilarityFactory, SimpleMLTQParserTest, TestLFUCache, TestTrieFacet, CollectionReloadTest, CachingDirectoryFactoryTest, DistributedTermsComponentTest, MinimalSchemaTest, FileUtilsTest, TestCodecSupport, TestFieldCacheSanityChecker, TestSystemIdResolver, FastVectorHighlighterTest, TestSimpleQParserPlugin, SpellCheckComponentTest, TestCursorMarkWithoutUniqueKey, TestGraphMLResponseWriter, MoreLikeThisHandlerTest, DOMUtilTest, TestRecoveryHdfs, TestQuerySenderNoQuery, TestSolrQueryResponse, DistributedIntervalFacetingTest, TestTrackingShardHandlerFactory, DocumentBuilderTest, TestDistributedGrouping, TermsComponentTest, ExplicitHLLTest, ZkStateWriterTest, TestSlowCompositeReaderWrapper, TestRandomRequestDistribution, UniqFieldsUpdateProcessorFactoryTest, ChangedSchemaMergeTest, SuggesterFSTTest, SpatialFilterTest, TestSolrCoreProperties, ZkSolrClientTest, TestQueryTypes, StressHdfsTest, TestTrie, TestUpdate, DistributedFacetPivotLargeTest, TestNoOpRegenerator, CSVRequestHandlerTest, ResponseHeaderTest, ZkControllerTest, TestSurroundQueryParser, ShardRoutingTest, TestSolrXml, TestConfigSetImmutable, TestDefaultSearchFieldResource, TestHashPartitioner, TestWordDelimiterFilterFactory, TestSimpleTrackingShardHandler, TestCloudDeleteByQuery, SolrIndexConfigTest, DataDrivenBlockJoinTest, TermVectorComponentTest, TestShortCircuitedRequests, TestBlendedInfixSuggestions, BigEndianAscendingWordDeserializerTest, TestNumericTerms32, ZkCLITest, DirectSolrSpellCheckerTest, TestHdfsUpdateLog, SolrInfoMBeanTest, MBeansHandlerTest, TestLeaderInitiatedRecoveryThread, TestSolrFieldCacheMBean, SoftAutoCommitTest, BasicFunctionalityTest, TestBM25SimilarityFactory, TestSolrDeletionPolicy2, TestEmbeddedSolrServerConstructors, TestQuerySenderListener, HdfsRestartWhileUpdatingTest, TestShardHandlerFactory, HighlighterTest, HdfsNNFailoverTest, DocExpirationUpdateProcessorFactoryTest, TestFilteredDocIdSet, OpenExchangeRatesOrgProviderTest, TestConfigReload, RestartWhileUpdatingTest, TestFieldCacheVsDocValues, AtomicUpdatesTest, SharedFSAutoReplicaFailoverUtilsTest, OpenCloseCoreStressTest, TestSchemaSimilarityResource, TestStressVersions, CollectionsAPIAsyncDistributedZkTest, ShardRoutingCustomTest, TestTolerantUpdateProcessorRandomCloud, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [425/612 (2!)] on J1 in 839.39s, 1 test, 1 error <<< FAILURES! [...truncated 633 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:531: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: There were test failures: 612 suites (10 ignored), 2564 tests, 2 errors, 33 ignored (18 assumptions) [seed: 36E19CFDDC88E5BE] Total time: 249 minutes 30 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: [email protected] For additional commands, e-mail: [email protected]
