Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/71/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=71875, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=71875, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:51314/g_j/jn: collection already exists: awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([A27123D9085C2687]: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) Build Log: [...truncated 11959 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/init-core-data-001 [junit4] 2> 2301031 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A27123D9085C2687]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 2301032 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A27123D9085C2687]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /g_j/jn [junit4] 2> 2301035 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2301035 INFO (Thread-61975) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2301035 INFO (Thread-61975) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2301135 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkTestServer start zk server on port:58015 [junit4] 2> 2301135 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301135 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301137 INFO (zkCallback-2079-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62c30aa4 name:ZooKeeperConnection Watcher:127.0.0.1:58015 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301137 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301137 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301137 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2301139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301139 INFO (zkCallback-2080-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49829b29 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301139 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2301140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2301145 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2301146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2301146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2301148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 2301148 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2301149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2301149 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2301150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2301151 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301151 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2301152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301152 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2301153 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301153 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2301154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2301155 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301155 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2301156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2301157 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301157 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301158 INFO (zkCallback-2082-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1841c20c name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301158 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301159 INFO (zkCallback-2083-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@55afd7e6 name:ZooKeeperConnection Watcher:127.0.0.1:58015 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2301160 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301161 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301161 INFO (zkCallback-2084-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d1b4de8 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301161 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301161 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/solrconfig.xml [junit4] 2> 2301162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 2301163 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 2301163 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 2301164 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/enumsConfig.xml [junit4] 2> 2301164 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 2301165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2301165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2301166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/stopwords.txt [junit4] 2> 2301166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 2301166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/protwords.txt [junit4] 2> 2301166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 2301167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/currency.xml [junit4] 2> 2301167 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 2301171 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/open-exchange-rates.json [junit4] 2> 2301171 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 2301172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2301172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 2301173 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/old_synonyms.txt [junit4] 2> 2301173 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 2301174 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/conf2/synonyms.txt [junit4] 2> 2301174 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 2301175 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 2301175 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 2301237 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1 [junit4] 2> 2301241 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2301243 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e064fa5{/g_j/jn,null,AVAILABLE} [junit4] 2> 2301248 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.ServerConnector Started ServerConnector@62eb608{SSL,[ssl, http/1.1]}{127.0.0.1:57163} [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.Server Started @2303398ms [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/tempDir-001/control/data, hostContext=/g_j/jn, hostPort=57163, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores} [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001' [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2301249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301250 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301250 INFO (zkCallback-2085-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39d4ebae name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301250 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301251 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301251 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2301251 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/solr.xml [junit4] 2> 2301254 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores [junit4] 2> 2301254 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.CoreContainer New CoreContainer 225703810 [junit4] 2> 2301254 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001] [junit4] 2> 2301255 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/lib [junit4] 2> 2301255 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2301255 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58015/solr [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2301256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301257 INFO (zkCallback-2088-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66a4e64 name:ZooKeeperConnection Watcher:127.0.0.1:58015 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2301257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/170432623@290ba4e0 [junit4] 2> 2301258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2301258 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b063b60 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2301258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2301259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2301260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2301262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2301263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2301264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2301264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2301265 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2301266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2301266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2301267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2301267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2301267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 2301268 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2301269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2301269 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2301270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000 [junit4] 2> 2301270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57163_g_j%2Fjn [junit4] 2> 2301270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2301271 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer Overseer (id=95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) starting [junit4] 2> 2301271 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2301274 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2301274 INFO (OverseerCollectionConfigSetProcessor-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2301274 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2301275 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57163_g_j%2Fjn [junit4] 2> 2301276 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57163_g_j%2Fjn [junit4] 2> 2301276 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2301276 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2301287 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 2301294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2301294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2301294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores [junit4] 2> 2301295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2301295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1 [junit4] 2> 2301295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2301295 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2301295 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2301296 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0 [junit4] 2> 2301296 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2301296 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2301296 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:57163/g_j/jn";, [junit4] 2> "node_name":"127.0.0.1:57163_g_j%2Fjn", [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> 2301296 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:57163/g_j/jn";, [junit4] 2> "node_name":"127.0.0.1:57163_g_j%2Fjn", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2301296 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 2301297 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2301297 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2301297 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2301298 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2302297 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2302297 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1' [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2302298 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2302299 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2302302 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2302315 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 2302321 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2302325 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2302398 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2302401 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2302402 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2302406 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2302408 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2302409 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2302409 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0 [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/] [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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@4404e1bf [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data [junit4] 2> 2302410 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/index/ [junit4] 2> 2302410 WARN (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2302411 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/index [junit4] 2> 2302411 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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=14, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=92.15625, floorSegmentMB=2.1611328125, forceMergeDeletesPctAllowed=23.588365791797944, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3750956316603986 [junit4] 2> 2302411 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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@14b4abb6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34ba64e2),segFN=segments_1,generation=1} [junit4] 2> 2302411 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2302411 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/ [junit4] 2> 2302412 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:57163_g_j%2Fjn 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302413 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302413 WARN (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302414 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2302415 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2302415 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2302416 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2302418 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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@14b4abb6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@34ba64e2),segFN=segments_1,generation=1} [junit4] 2> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f4af653[collection1] main] [junit4] 2> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302419 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2302420 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2302421 INFO (searcherExecutor-5946-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f4af653[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2302421 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302421 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302421 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302421 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn 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 1535141670855639040 [junit4] 2> 2302423 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1535141670855639040 [junit4] 2> 2302423 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2302423 INFO (coreLoadExecutor-5945-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2302423 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:57163/g_j/jn collection:control_collection shard:shard1 [junit4] 2> 2302424 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302425 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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/95946354337644551-core_node1-n_0000000000 [junit4] 2> 2302425 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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=180000 [junit4] 2> 2302426 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2302426 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302426 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection"} current state version: 1 [junit4] 2> 2302426 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:57163/g_j/jn/collection1/ [junit4] 2> 2302426 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2302426 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:57163/g_j/jn/collection1/ has no replicas [junit4] 2> 2302427 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2302428 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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/95946354337644551-core_node1-n_0000000000 [junit4] 2> 2302429 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:57163/g_j/jn/collection1/ shard1 [junit4] 2> 2302429 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"https://127.0.0.1:57163/g_j/jn";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2302530 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2302579 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:57163/g_j/jn/collection1/ and leader is https://127.0.0.1:57163/g_j/jn/collection1/ [junit4] 2> 2302579 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:57163/g_j/jn [junit4] 2> 2302579 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2302579 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2302580 INFO (coreZkRegister-5939-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:57163_g_j%2Fjn 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> 2302580 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] 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":"https://127.0.0.1:57163/g_j/jn";, [junit4] 2> "node_name":"127.0.0.1:57163_g_j%2Fjn", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 2302580 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:57163/g_j/jn";, [junit4] 2> "node_name":"127.0.0.1:57163_g_j%2Fjn", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2302682 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2302797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2302797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2302798 INFO (zkCallback-2093-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a545ad9 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2302798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2302798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2302798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2302799 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2302799 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2302800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2302800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 2302800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2302800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2302800 INFO (zkCallback-2094-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@153a7f23 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2302801 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2302801 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2302804 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 2302804 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2302885 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/cores/collection1 [junit4] 2> 2302886 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001 [junit4] 2> 2302887 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 2302892 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@76befee2{/g_j/jn,null,AVAILABLE} [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.ServerConnector Started ServerConnector@14d6dac5{SSL,[ssl, http/1.1]}{127.0.0.1:34553} [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.e.j.s.Server Started @2305043ms [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/g_j/jn, hostPort=34553, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/cores} [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001' [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2302894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2302895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2302895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2302895 INFO (zkCallback-2095-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e4316db name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2302895 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2302896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2302896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2302896 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/solr.xml [junit4] 2> 2302899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/cores [junit4] 2> 2302899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.CoreContainer New CoreContainer 935945820 [junit4] 2> 2302900 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001] [junit4] 2> 2302900 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_A27123D9085C2687-001/shard-1-001/lib [junit4] 2> 2302900 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] 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> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58015/solr [junit4] 2> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2302901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2302903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2302903 INFO (zkCallback-2098-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1eb0896c name:ZooKeeperConnection Watcher:127.0.0.1:58015 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2302903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2302903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2302904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/170432623@54b49c7 [junit4] 2> 2302904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2302905 INFO (zkCallback-2099-thread-1-processing-n:127.0.0.1:34553_g_j%2Fjn) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66ab103 name:ZooKeeperConnection Watcher:127.0.0.1:58015/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2302905 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2302908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2302908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Loaded cluster properties: {urlScheme=https} [junit4] 2> 2302908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 2302909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2302910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95946354337644556-127.0.0.1:34553_g_j%2Fjn-n_0000000001 [junit4] 2> 2302910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000 to know if I could be the leader [junit4] 2> 2302910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A27123D9085C2687]) [n:127.0.0.1:34553_g_j%2Fjn ] o.a.s.c.ZkController Publish node=127.0.0.1:34553_g_j%2Fjn as DOWN [junit4] 2> 2302911 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:34553_g_j%2Fjn"} current state version: 3 [junit4] 2> 2302911 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34553_g_j%2Fjn [junit4] 2> 2302911 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 2302913 INFO (zkCallback-2089-thread-1-processing-n:127.0.0.1:57163_g_j%2Fjn) [n:127.0.0.1:57163_g_j%2Fjn ] 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> 2302913 INFO (zkCallback-2093-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> 2302913 INFO (zkCallback-2099-thread-1-processing-n:127.0.0.1:34553_g_j%2Fjn) [n:127.0.0.1:34553_g_j%2Fjn ] 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> 2303015 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 2147483647 [junit4] 2> 2303016 WARN (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer Bad version writing to ZK using compare-and-set, will force refresh cluster state [junit4] 2> org.apache.zookeeper.KeeperException$BadVersionException: KeeperErrorCode = BadVersion for /collections/collection1/state.json [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:115) [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51) [junit4] 2> at org.apache.zookeeper.ZooKeeper.setData(ZooKeeper.java:1270) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:365) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$8.execute(SolrZkClient.java:362) [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.setData(SolrZkClient.java:362) [junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:232) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:240) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 2303016 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 2303017 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.Overseer processMessage: workQueueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:34553_g_j%2Fjn"} [junit4] 2> 2303017 INFO (OverseerStateUpdate-95946354337644551-127.0.0.1:57163_g_j%2Fjn-n_0000000000) [n:127.0.0.1:57163_g_j%2Fjn ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:34553_g_j%2Fjn [junit4] 2> 2303017 INFO (zkCallback-2093-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 [...truncated too long message...] ient.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 #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=71876, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:51314/g_j/jn: collection already exists: awholynewstresscollection_collection5_0 [junit4] > at __randomizedtesting.SeedInfo.seed([A27123D9085C2687]: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 #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=71872, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:51314/g_j/jn: collection already exists: awholynewstresscollection_collection1_0 [junit4] > at __randomizedtesting.SeedInfo.seed([A27123D9085C2687]: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> 2690280 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A27123D9085C2687]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> May 23, 2016 5:46:11 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=FST50, range_facet_l_dv=Lucene50(blocksize=128), _version_=FST50, multiDefault=BlockTreeOrds(blocksize=128), a_t=FST50, intDefault=FST50, a_i1=FST50, id=Lucene50(blocksize=128), range_facet_i_dv=FST50, text=PostingsFormat(name=LuceneFixedGap), range_facet_l=FST50, timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1962, maxMBSortInHeap=5.246039074133476, sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=th, timezone=Africa/Monrovia [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=165230840,total=514326528 [junit4] 2> NOTE: All tests run in this JVM: [SolrCloudExampleTest, IndexSchemaRuntimeFieldTest, TestPhraseSuggestions, TestDistributedSearch, CursorPagingTest, CurrencyFieldOpenExchangeTest, TestXmlQParser, TestReloadDeadlock, SmileWriterTest, TestNamedUpdateProcessors, CopyFieldTest, SyncSliceTest, TestSortingResponseWriter, TestPseudoReturnFields, DistanceUnitsTest, FileBasedSpellCheckerTest, TestExactStatsCache, CollectionTooManyReplicasTest, AnalysisErrorHandlingTest, DocumentAnalysisRequestHandlerTest, ResponseLogComponentTest, PingRequestHandlerTest, SSLMigrationTest, TestLazyCores, CreateCollectionCleanupTest, HdfsRestartWhileUpdatingTest, TestFaceting, TestDistributedStatsComponentCardinality, TestDistribDocBasedVersion, AsyncCallRequestStatusResponseTest, TestRandomDVFaceting, HdfsDirectoryFactoryTest, CloudExitableDirectoryReaderTest, RankQueryTest, TestCloudPivotFacet, SolrCoreTest, ClassificationUpdateProcessorFactoryTest, TestDocBasedVersionConstraints, TestSolr4Spatial, PKIAuthenticationIntegrationTest, TestTolerantUpdateProcessorRandomCloud, TestLRUCache, CacheHeaderTest, PrimUtilsTest, BasicDistributedZkTest, JsonLoaderTest, OverseerTaskQueueTest, ForceLeaderTest, TestOnReconnectListenerSupport, ReplicationFactorTest, HdfsNNFailoverTest, BasicZkTest, SystemInfoHandlerTest, TestSmileRequest, TestFoldingMultitermQuery, RulesTest, TestDocSet, TestArbitraryIndexDir, TestConfig, TestAnalyzedSuggestions, HdfsChaosMonkeyNothingIsSafeTest, ExternalFileFieldSortTest, OverseerCollectionConfigSetProcessorTest, TestRestoreCore, TestCustomSort, DistributedFacetPivotLargeTest, TestSSLRandomization, TestRTGBase, TestClassicSimilarityFactory, HdfsDirectoryTest, TestCustomDocTransformer, ConnectionReuseTest, TestLuceneMatchVersion, TestFastOutputStream, PolyFieldTest, TestGraphTermsQParserPlugin, TestDynamicLoading, TestOmitPositions, GraphQueryTest, TestOverriddenPrefixQueryForCustomFieldType, TestManagedResourceStorage, TestSolrXml, DefaultValueUpdateProcessorTest, SpatialRPTFieldTypeTest, DateRangeFieldTest, DistributedFacetPivotSmallTest, SparseHLLTest, ConcurrentDeleteAndCreateCollectionTest, FullHLLTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, PreAnalyzedFieldTest, TestDefaultStatsCache, TestJsonRequest, TestFileDictionaryLookup, TestReplicaProperties, DistributedQueryComponentOptimizationTest, TestRealTimeGet, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest] [junit4] Completed [298/598 (1!)] on J0 in 389.99s, 1 test, 1 error <<< FAILURES! [...truncated 943 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: 598 suites (10 ignored), 2435 tests, 1 error, 32 ignored (17 assumptions) [seed: A27123D9085C2687] Total time: 235 minutes 13 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]
