Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/924/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=52426, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=52426, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] at __randomizedtesting.SeedInfo.seed([ED50840303638498:6504BBD9AD9FE960]:0) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39255/o_wnb/d: Could not find collection : awholynewstresscollection_collection3_0 at __randomizedtesting.SeedInfo.seed([ED50840303638498]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:893) Build Log: [...truncated 11396 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/init-core-data-001 [junit4] 2> 1535351 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 1535351 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /o_wnb/d [junit4] 2> 1535355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1535355 INFO (Thread-46015) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1535355 INFO (Thread-46015) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1535455 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkTestServer start zk server on port:51825 [junit4] 2> 1535455 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535456 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535458 INFO (zkCallback-1355-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@254cfef3 name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535458 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1535461 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535462 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535463 INFO (zkCallback-1356-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4eefb97f name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1535466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1535468 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1535469 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1535471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1535471 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1535474 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1535474 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1535476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1535476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1535478 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1535478 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1535480 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1535480 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1535481 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1535482 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1535483 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1535484 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1535485 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1535485 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1535487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1535487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1535489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1535489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1535490 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1535491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1535493 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535494 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535495 INFO (zkCallback-1357-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ddb2fd6 name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535495 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535496 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1535497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535498 INFO (zkCallback-1358-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12bff42 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1535499 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1535501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1535502 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1535503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1535504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1535505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1535505 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1535507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1535507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1535509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1535509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1535510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1535511 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1535512 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1535512 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1535514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1535514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1535518 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1535518 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1535521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1535521 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1535524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1535524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1535613 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1 [junit4] 2> 1535615 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1535617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ac15012{/o_wnb/d,null,AVAILABLE} [junit4] 2> 1535617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.ServerConnector Started ServerConnector@293c5e85{HTTP/1.1}{127.0.0.1:60845} [junit4] 2> 1535617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.Server Started @1538923ms [junit4] 2> 1535617 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/tempDir-001/control/data, hostContext=/o_wnb/d, hostPort=60845, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores} [junit4] 2> 1535618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1535618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001' [junit4] 2> 1535618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1535618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1535618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535620 INFO (zkCallback-1359-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c287497 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1535621 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/solr.xml [junit4] 2> 1535626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores [junit4] 2> 1535626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer New CoreContainer 2108093829 [junit4] 2> 1535626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001] [junit4] 2> 1535626 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/lib [junit4] 2> 1535626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] 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> 1535628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1535628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1535628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1535628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51825/solr [junit4] 2> 1535629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1535629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1535629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535631 INFO (zkCallback-1362-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@493d2044 name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535633 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1535635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1535636 INFO (zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@cdf70a6 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1535636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1535636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1535639 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1535642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1535644 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1535646 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1535649 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1535650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1535651 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1535653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1535654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60845_o_wnb%2Fd [junit4] 2> 1535654 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60845_o_wnb%2Fd [junit4] 2> 1535655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1535656 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1535657 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1535658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000 [junit4] 2> 1535658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60845_o_wnb%2Fd [junit4] 2> 1535658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1535659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Overseer (id=95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) starting [junit4] 2> 1535661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1535666 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1535666 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1535667 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1535667 INFO (OverseerCollectionConfigSetProcessor-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1535682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1535682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1535682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores [junit4] 2> 1535683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1535683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1 [junit4] 2> 1535683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1535685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1535685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1535685 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1535685 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1535687 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60845/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd", [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> 1535687 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60845/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1535687 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1535688 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1535688 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1535689 INFO (zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1536688 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1536688 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1' [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1536689 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1536691 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1536696 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1536710 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1536719 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1536725 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1536828 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1536833 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1536834 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1536842 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1536844 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1536846 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1536846 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1536848 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1536848 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1536848 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1536848 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/] [junit4] 2> 1536848 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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@79e3e69b [junit4] 2> 1536849 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data [junit4] 2> 1536849 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/index/ [junit4] 2> 1536849 WARN (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1536849 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/index [junit4] 2> 1536849 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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=19, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1536850 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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@6cf74af9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2ded6),segFN=segments_1,generation=1} [junit4] 2> 1536850 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1536850 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/ [junit4] 2> 1536850 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1536854 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1536854 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1536854 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1536854 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1536855 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1536855 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536855 WARN (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536857 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1536858 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1536859 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1536860 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1536863 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1536863 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536864 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536864 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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=5, maxMergeAtOnceExplicit=6, maxMergedSegmentMB=36.9208984375, floorSegmentMB=0.84375, forceMergeDeletesPctAllowed=12.718728979334532, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4737649688065727 [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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@6cf74af9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12f2ded6),segFN=segments_1,generation=1} [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@17691bd[collection1] main] [junit4] 2> 1536865 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536866 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1536867 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1536868 INFO (searcherExecutor-3677-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17691bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1536868 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536868 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd 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 1525285457423761408 [junit4] 2> 1536870 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1525285457423761408 [junit4] 2> 1536870 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1536871 INFO (coreLoadExecutor-3676-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1536871 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:60845/o_wnb/d collection:control_collection shard:shard1 [junit4] 2> 1536872 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536874 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd 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/95330340989894662-core_node1-n_0000000000 [junit4] 2> 1536875 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536876 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1536876 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1536876 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1536876 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60845/o_wnb/d/collection1/ [junit4] 2> 1536877 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1536877 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60845/o_wnb/d/collection1/ has no replicas [junit4] 2> 1536878 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1536879 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1536881 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60845/o_wnb/d/collection1/ shard1 [junit4] 2> 1536881 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:60845/o_wnb/d", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1536983 INFO (zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1537032 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:60845/o_wnb/d/collection1/ and leader is http://127.0.0.1:60845/o_wnb/d/collection1/ [junit4] 2> 1537032 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60845/o_wnb/d [junit4] 2> 1537032 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1537032 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1537032 INFO (coreZkRegister-3670-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60845_o_wnb%2Fd 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> 1537034 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60845/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd", [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> 1537034 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60845/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:60845_o_wnb%2Fd", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1537136 INFO (zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1537186 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1537187 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1537188 INFO (zkCallback-1365-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c6350 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1537189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1537189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1537189 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1537193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1537193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1537193 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1537194 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1537196 INFO (zkCallback-1366-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3aed3e24 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1537196 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1537197 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1537199 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1537199 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1537287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores/collection1 [junit4] 2> 1537287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001 [junit4] 2> 1537288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1537290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@414e9ecf{/o_wnb/d,null,AVAILABLE} [junit4] 2> 1537290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2e6a7b5d{HTTP/1.1}{127.0.0.1:35428} [junit4] 2> 1537290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.e.j.s.Server Started @1540597ms [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/o_wnb/d, hostPort=35428, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores} [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001' [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1537291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1537292 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1537293 INFO (zkCallback-1367-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c22cd41 name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1537293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1537294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1537295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1537295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/solr.xml [junit4] 2> 1537300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores [junit4] 2> 1537300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer New CoreContainer 249742675 [junit4] 2> 1537300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001] [junit4] 2> 1537301 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/lib [junit4] 2> 1537301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] 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> 1537302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1537302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1537302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1537303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:51825/solr [junit4] 2> 1537303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1537303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1537304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1537304 INFO (zkCallback-1370-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f3c4fa9 name:ZooKeeperConnection Watcher:127.0.0.1:51825 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1537305 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1537306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1537308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1537309 INFO (zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1bfe89ca name:ZooKeeperConnection Watcher:127.0.0.1:51825/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1537309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1537310 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1537317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1538325 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35428_o_wnb%2Fd [junit4] 2> 1538325 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35428_o_wnb%2Fd [junit4] 2> 1538326 INFO (zkCallback-1365-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [1]) [junit4] 2> 1538326 INFO (zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd ] 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> 1538327 INFO (zkCallback-1363-thread-1-processing-n:127.0.0.1:60845_o_wnb%2Fd) [n:127.0.0.1:60845_o_wnb%2Fd ] 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> 1538329 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1538329 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95330340989894667-127.0.0.1:35428_o_wnb%2Fd-n_0000000001 [junit4] 2> 1538331 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000 to know if I could be the leader [junit4] 2> 1538353 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1538353 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1538354 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores [junit4] 2> 1538355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1538355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_ED50840303638498-001/shard-1-001/cores/collection1 [junit4] 2> 1538355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1538356 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1538356 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1538357 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 1538358 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 1538358 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35428/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:35428_o_wnb%2Fd", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1538358 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 1538358 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1538358 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35428/o_wnb/d", [junit4] 2> "node_name":"127.0.0.1:35428_o_wnb%2Fd", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1538358 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1538359 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1538359 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1538359 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[ED50840303638498]) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1538461 INFO (OverseerStateUpdate-95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000) [n:127.0.0.1:60845_o_wnb%2Fd ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1538461 INFO (zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 1538462 INFO (zkCallback-1371-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [0] to [1] [junit4] 2> 1539358 INFO (coreLoadExecutor-3687-thread-1-processing-n:127.0.0.1:35428_o_wnb%2Fd) [n:127.0.0.1:35428_o_wnb%2Fd c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [...truncated too long message...] [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection4_0/leader_elect/shard2/election/95330340989894673-core_node18-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection4_0/leader_elect/shard1/election/95330340989894670-core_node19-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard2/election/95330340989894667-core_node17-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection5_0/leader_elect/shard5/election/95330340989894673-core_node7-n_0000000000 [junit4] 2> 2 /solr/collections/acollectionafterbaddelete/state.json [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard1/election/95330340989894676-core_node2-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/95330340989894662-127.0.0.1:60845_o_wnb%2Fd-n_0000000000 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard5/election/95330340989894667-core_node5-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard4/election/95330340989894673-core_node13-n_0000000001 [junit4] 2> 2 /solr/collections/addReplicaColl/leader_elect/shard2/election/95330340989894673-core_node3-n_0000000000 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard3/election/95330340989894673-core_node17-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection2_0/leader_elect/shard3/election/95330340989894673-core_node4-n_0000000001 [junit4] 2> 2 /solr/collections/halfdeletedcollection/state.json [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection3_0/leader_elect/shard1/election/95330340989894670-core_node15-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard2/election/95330340989894676-core_node1-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewstresscollection_collection0_0/leader_elect/shard5/election/95330340989894670-core_node12-n_0000000001 [junit4] 2> 2 /solr/collections/awholynewcollection_4/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 20 /solr/collections [junit4] 2> 19 /solr/live_nodes [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue-work [junit4] 2> 2 /solr/overseer/queue [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=test -Dtests.seed=ED50840303638498 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=tr -Dtests.timezone=Australia/Canberra -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 241s J1 | CollectionsAPIDistributedZkTest.test <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=52426, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > at __randomizedtesting.SeedInfo.seed([ED50840303638498:6504BBD9AD9FE960]:0) [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:39255/o_wnb/d: Could not find collection : awholynewstresscollection_collection3_0 [junit4] > at __randomizedtesting.SeedInfo.seed([ED50840303638498]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:893) [junit4] 2> 1776672 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[ED50840303638498]-worker) [n:127.0.0.1:39255_o_wnb%2Fd c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Feb 04, 2016 10:43:25 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=Lucene50(blocksize=128), range_facet_l_dv=FSTOrd50, _version_=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128), a_i1=Lucene50(blocksize=128), id=FSTOrd50, range_facet_i_dv=Lucene50(blocksize=128), text=PostingsFormat(name=Direct), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Lucene54)}, sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=tr, timezone=Australia/Canberra [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=151245272,total=478674944 [junit4] 2> NOTE: All tests run in this JVM: [AsyncMigrateRouteKeyTest, CollectionReloadTest, TestDefaultSearchFieldResource, TestPartialUpdateDeduplication, ZkControllerTest, TestSolrCLIRunExample, BlockJoinFacetRandomTest, TestAuthorizationFramework, DistributedQueueTest, TestFieldTypeResource, ConnectionManagerTest, MergeStrategyTest, TestRequestForwarding, ChaosMonkeyNothingIsSafeTest, TestSortByMinMaxFunction, TestAddFieldRealTimeGet, SmileWriterTest, CdcrUpdateLogTest, ZkCLITest, DistributedQueryComponentCustomSortTest, IndexBasedSpellCheckerTest, TestSolrCoreProperties, BigEndianAscendingWordSerializerTest, TestPivotHelperCode, SaslZkACLProviderTest, TestBulkSchemaConcurrent, TestQuerySenderListener, TestRawTransformer, HighlighterMaxOffsetTest, TestEmbeddedSolrServerConstructors, MoreLikeThisHandlerTest, SpellPossibilityIteratorTest, TestJsonFacets, TestCursorMarkWithoutUniqueKey, TestLFUCache, SchemaVersionSpecificBehaviorTest, TestDistributedGrouping, TestUniqueKeyFieldResource, AnalyticsMergeStrategyTest, SolrIndexConfigTest, SolrIndexSplitterTest, SparseHLLTest, ChaosMonkeySafeLeaderTest, DocumentBuilderTest, TestSimpleTrackingShardHandler, DistributedExpandComponentTest, DistanceUnitsTest, FastVectorHighlighterTest, HdfsWriteToMultipleCollectionsTest, TestFilteredDocIdSet, TermVectorComponentTest, ConvertedLegacyTest, ExitableDirectoryReaderTest, TestAnalyzeInfixSuggestions, HdfsSyncSliceTest, HdfsChaosMonkeySafeLeaderTest, DistributedIntervalFacetingTest, TestSolrDynamicMBean, NotRequiredUniqueKeyTest, CSVRequestHandlerTest, HdfsBasicDistributedZk2Test, TestComplexPhraseQParserPlugin, BadCopyFieldTest, TestCloudPivotFacet, SolrPluginUtilsTest, TestSchemaManager, TestPerFieldSimilarityWithDefaultOverride, ZkSolrClientTest, SuggesterFSTTest, DistanceFunctionTest, SuggestComponentTest, OverseerRolesTest, TestInfoStreamLogging, TestDocumentBuilder, FullHLLTest, SpellCheckCollatorTest, TestReqParamsAPI, TestSystemIdResolver, NumericFieldsTest, TestSurroundQueryParser, SpatialFilterTest, TestDistribIDF, TestReloadDeadlock, DocValuesTest, ShardRoutingCustomTest, TestConfigSets, TestManagedSchemaFieldTypeResource, RecoveryAfterSoftCommitTest, BasicFunctionalityTest, AliasIntegrationTest, TestExceedMaxTermLength, TestTrie, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [188/575 (1!)] on J1 in 241.80s, 1 test, 1 error <<< FAILURES! [...truncated 1254 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 575 suites (8 ignored), 2316 tests, 1 error, 33 ignored (18 assumptions) [seed: ED50840303638498] Total time: 295 minutes 11 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org