Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/880/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=61974, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=61974, name=collection4, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:44219, http://127.0.0.1:41121, http://127.0.0.1:60207, http://127.0.0.1:46906, http://127.0.0.1:41625] at __randomizedtesting.SeedInfo.seed([1EF0D91F3A098E50]:0) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:891) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:44219, http://127.0.0.1:41121, http://127.0.0.1:60207, http://127.0.0.1:46906, http://127.0.0.1:41625] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46906: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) ... 7 more Build Log: [...truncated 10836 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/init-core-data-001 [junit4] 2> 2149123 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1EF0D91F3A098E50]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 2149123 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1EF0D91F3A098E50]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 2149133 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 2149138 INFO (Thread-54047) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 2149138 INFO (Thread-54047) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 2149235 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkTestServer start zk server on port:60158 [junit4] 2> 2149235 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2149241 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2149259 INFO (zkCallback-2280-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@28f8bb35 name:ZooKeeperConnection Watcher:127.0.0.1:60158 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2149259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2149260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2149260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 2149265 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2149280 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2149281 INFO (zkCallback-2281-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@605986dc name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2149281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2149281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2149281 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 2149282 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 2149283 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 2149284 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 2149285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 2149287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149287 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 2149288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 2149289 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149290 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 2149291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 2149292 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149292 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 2149293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 2149294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149294 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 2149295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 2149296 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149297 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 2149298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 2149697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1 [junit4] 2> 2149699 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 2149702 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6c16f3c8{/,null,AVAILABLE} [junit4] 2> 2149707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.ServerConnector Started ServerConnector@34d7bf0b{HTTP/1.1,[http/1.1]}{127.0.0.1:41625} [junit4] 2> 2149707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.Server Started @2152924ms [junit4] 2> 2149707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/tempDir-001/control/data, hostContext=/, hostPort=41625, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores} [junit4] 2> 2149707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2149707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001' [junit4] 2> 2149708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2149708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2149708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2149710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2149710 INFO (zkCallback-2282-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@369cb474 name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2149710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2149711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2149713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2149713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/solr.xml [junit4] 2> 2149718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores [junit4] 2> 2149718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer New CoreContainer 1348979021 [junit4] 2> 2149718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001] [junit4] 2> 2149719 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/lib [junit4] 2> 2149719 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2149721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2149722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2149723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2149723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60158/solr [junit4] 2> 2149723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2149723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2149727 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2149742 INFO (zkCallback-2284-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1cd994de name:ZooKeeperConnection Watcher:127.0.0.1:60158 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2149743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2149743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2149749 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2149757 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6195ab67 name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2149757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2149758 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 2149760 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 2149762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 2149763 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 2149765 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 2149767 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 2149773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 2149774 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 2149775 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 2149777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41625_ [junit4] 2> 2149777 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41625_ [junit4] 2> 2149781 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 2149783 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 2149787 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2149788 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95022641820073988-127.0.0.1:41625_-n_0000000000 [junit4] 2> 2149789 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41625_ [junit4] 2> 2149789 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 2149799 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.Overseer Overseer (id=95022641820073988-127.0.0.1:41625_-n_0000000000) starting [junit4] 2> 2149800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 2149830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 2149837 INFO (OverseerCollectionConfigSetProcessor-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 2149841 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 2149841 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2149869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2149870 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2149870 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores [junit4] 2> 2149872 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] 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> 2149872 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1 [junit4] 2> 2149872 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2149878 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 2149878 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:41625_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2149878 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2149878 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2149880 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:41625";, [junit4] 2> "node_name":"127.0.0.1:41625_", [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> 2149880 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2149880 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:41625";, [junit4] 2> "node_name":"127.0.0.1:41625_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2149881 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 2149881 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 2149882 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] 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> 2150881 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2150881 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 2150881 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2150881 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 2150882 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2150882 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1' [junit4] 2> 2150882 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2150882 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2150883 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2150888 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2150892 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2150925 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2150938 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2151043 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2151068 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2151075 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2151127 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2151130 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2151134 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2151139 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2151139 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2151139 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2151143 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2151144 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2151159 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0 [junit4] 2> 2151159 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 2151159 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2151161 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/] [junit4] 2> 2151161 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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@14da7975 [junit4] 2> 2151161 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data [junit4] 2> 2151161 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/index/ [junit4] 2> 2151162 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2151162 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/index [junit4] 2> 2151162 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@7696055f [junit4] 2> 2151162 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@4b09acec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2047bd39),segFN=segments_1,generation=1} [junit4] 2> 2151163 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151176 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151177 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151177 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151177 WARN (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151178 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2151179 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2151180 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2151181 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 2151181 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/ [junit4] 2> 2151187 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41625_ 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/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 2151188 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 2151192 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151197 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151197 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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=48, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.22265625, noCFSRatio=0.0] [junit4] 2> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@4b09acec lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2047bd39),segFN=segments_1,generation=1} [junit4] 2> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 2151198 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@760a8b4a[collection1] main [junit4] 2> 2151199 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151199 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151199 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151200 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 2151200 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 2151200 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151202 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151203 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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> 2151203 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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 1520362271280201728 [junit4] 2> 2151204 INFO (searcherExecutor-3678-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@760a8b4a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 2151205 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ 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 1520362271280201728 [junit4] 2> 2151205 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 2151205 INFO (coreLoadExecutor-3677-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 2151206 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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:41625 collection:control_collection shard:shard1 [junit4] 2> 2151206 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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> 2151209 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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/95022641820073988-core_node1-n_0000000000 [junit4] 2> 2151209 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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> 2151211 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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> 2151211 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 2151212 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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> 2151212 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41625/collection1/ [junit4] 2> 2151212 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 2151212 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:41625/collection1/ has no replicas [junit4] 2> 2151213 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 2151215 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 2151220 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:41625";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 2151220 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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:41625/collection1/ shard1 [junit4] 2> 2151323 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] 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> 2151373 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:41625/collection1/ and leader is http://127.0.0.1:41625/collection1/ [junit4] 2> 2151373 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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:41625 [junit4] 2> 2151373 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 2151373 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 2151373 INFO (coreZkRegister-3671-thread-1-processing-n:127.0.0.1:41625_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41625_ 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> 2151375 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:41625";, [junit4] 2> "node_name":"127.0.0.1:41625_", [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> 2151376 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:41625";, [junit4] 2> "node_name":"127.0.0.1:41625_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 2151380 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2151388 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2151404 INFO (zkCallback-2287-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2bcb525a name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2151404 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2151405 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2151405 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2151407 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 2151480 INFO (zkCallback-2287-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 2151480 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] 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> 2151865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1 [junit4] 2> 2151866 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001 [junit4] 2> 2151866 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 2151868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@36457fda{/,null,AVAILABLE} [junit4] 2> 2151868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.ServerConnector Started ServerConnector@495375e{HTTP/1.1,[http/1.1]}{127.0.0.1:60207} [junit4] 2> 2151868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.e.j.s.Server Started @2155085ms [junit4] 2> 2151868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=60207, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores} [junit4] 2> 2151869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 2151869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001' [junit4] 2> 2151869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2151869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2151869 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2151872 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2151879 INFO (zkCallback-2288-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@51f5a404 name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2151879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2151879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2151894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 2151894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/solr.xml [junit4] 2> 2151899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores [junit4] 2> 2151899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer New CoreContainer 1802466715 [junit4] 2> 2151899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001] [junit4] 2> 2151899 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/lib [junit4] 2> 2151899 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] 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> 2151900 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 2151901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 2151901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 2151901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60158/solr [junit4] 2> 2151901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 2151901 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 2151906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2151907 INFO (zkCallback-2290-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1a864a81 name:ZooKeeperConnection Watcher:127.0.0.1:60158 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2151907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2151907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 2151915 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 2151923 INFO (zkCallback-2291-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3953e852 name:ZooKeeperConnection Watcher:127.0.0.1:60158/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 2151923 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 2151928 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 2152932 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60207_ [junit4] 2> 2152933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60207_ [junit4] 2> 2152934 INFO (zkCallback-2287-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> 2152934 INFO (zkCallback-2291-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ ] 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> 2152934 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] 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> 2152935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 2152936 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95022641820073992-127.0.0.1:60207_-n_0000000001 [junit4] 2> 2152937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95022641820073988-127.0.0.1:41625_-n_0000000000 to know if I could be the leader [junit4] 2> 2152947 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 2152947 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 2152948 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores [junit4] 2> 2152949 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] 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> 2152949 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1 [junit4] 2> 2152949 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 2152953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 2152953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[1EF0D91F3A098E50]) [n:127.0.0.1:60207_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 2152953 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 2152953 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 2152955 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 2152956 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:60207";, [junit4] 2> "node_name":"127.0.0.1:60207_", [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> 2152956 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] 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:60207";, [junit4] 2> "node_name":"127.0.0.1:60207_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 2152956 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 2152956 INFO (OverseerStateUpdate-95022641820073988-127.0.0.1:41625_-n_0000000000) [n:127.0.0.1:41625_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 2153058 INFO (zkCallback-2291-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2153058 INFO (zkCallback-2287-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2153059 INFO (zkCallback-2285-thread-1-processing-n:127.0.0.1:41625_) [n:127.0.0.1:41625_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 2153955 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 2153955 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 2153956 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 2153956 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 2153956 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 2153957 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1' [junit4] 2> 2153957 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 2153957 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 2153958 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 2153962 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 2153966 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 2153973 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 2153979 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 2154092 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 2154098 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 2154099 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 2154107 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2154111 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 2154115 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2154115 WARN (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2154116 WARN (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2154116 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 2154117 WARN (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 2154117 WARN (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 2154118 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 2154118 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 2154120 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 2154120 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1/data/] [junit4] 2> 2154120 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@14da7975 [junit4] 2> 2154122 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1/data [junit4] 2> 2154122 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 2154122 WARN (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 2154122 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_1EF0D91F3A098E50-001/shard-1-001/cores/collection1/data/index [junit4] 2> 2154124 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6dbef9ac [junit4] 2> 2154126 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@5e1a0ac5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6ab5d64a),segFN=segments_1,generation=1} [junit4] 2> 2154126 INFO (coreLoadExecutor-3688-thread-1-processing-n:127.0.0.1:60207_) [n:127.0.0.1:60207_ c:collect [...truncated too long message...] at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=61973, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46906: collection already exists: awholynewstresscollection_collection3_1 [junit4] > at __randomizedtesting.SeedInfo.seed([1EF0D91F3A098E50]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [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:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=61972, name=collection2, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46906: collection already exists: awholynewstresscollection_collection2_1 [junit4] > at __randomizedtesting.SeedInfo.seed([1EF0D91F3A098E50]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [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:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=61971, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:46906: Could not find collection : awholynewstresscollection_collection1_1 [junit4] > at __randomizedtesting.SeedInfo.seed([1EF0D91F3A098E50]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [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:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888) [junit4] 2> 2483231 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[1EF0D91F3A098E50]-worker) [n:127.0.0.1:41121_ c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=4, maxDocsPerChunk=10, blockSize=975), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=4, blockSize=975)), sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ko_KR, timezone=Europe/Nicosia [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=129888400,total=487587840 [junit4] 2> NOTE: All tests run in this JVM: [TestCSVLoader, AnalyticsQueryTest, TestCopyFieldCollectionResource, SolrCoreTest, TestManagedSchema, TestPerFieldSimilarityWithDefaultOverride, AlternateDirectoryTest, RankQueryTest, TestFreeTextSuggestions, TestConfigSetsAPIExclusivity, BasicDistributedZkTest, BasicZkTest, DateRangeFieldTest, TestJsonRequest, PeerSyncTest, TestCloudPivotFacet, TestSha256AuthenticationProvider, TestCloudManagedSchemaConcurrent, TestDistributedSearch, HdfsLockFactoryTest, TestSolr4Spatial2, HttpPartitionTest, LeaderElectionIntegrationTest, DefaultValueUpdateProcessorTest, TestDocSet, TestMiniSolrCloudClusterBase, TestDistribDocBasedVersion, ExternalFileFieldSortTest, CdcrReplicationHandlerTest, ReturnFieldsTest, URLClassifyProcessorTest, TestOmitPositions, TestLMDirichletSimilarityFactory, ResourceLoaderTest, TestSolr4Spatial, BigEndianAscendingWordDeserializerTest, TestStressLucene, SuggestComponentContextFilterQueryTest, SystemInfoHandlerTest, TestSchemaVersionResource, CacheHeaderTest, OverseerRolesTest, TestRandomFaceting, PingRequestHandlerTest, TestRandomDVFaceting, HdfsThreadLeakTest, CursorPagingTest, AliasIntegrationTest, TestManagedSchemaFieldResource, CoreAdminCreateDiscoverTest, TestExpandComponent, TestFaceting, CachingDirectoryFactoryTest, TestAtomicUpdateErrorCases, SaslZkACLProviderTest, TestHighlightDedupGrouping, ZkStateWriterTest, OutOfBoxZkACLAndCredentialsProvidersTest, StandardRequestHandlerTest, TestSolrCloudWithKerberosAlt, TestSolrQueryParserResource, TestOrdValues, TestSearcherReuse, TestExceedMaxTermLength, TestRangeQuery, TestTrieFacet, DeleteLastCustomShardedReplicaTest, TestComponentsName, TestRawResponseWriter, DistributedFacetPivotLongTailTest, SynonymTokenizerTest, DistribCursorPagingTest, JsonLoaderTest, TestLuceneMatchVersion, XsltUpdateRequestHandlerTest, ResponseLogComponentTest, TestManagedSynonymFilterFactory, UUIDFieldTest, HdfsBasicDistributedZk2Test, SOLR749Test, DistributedFacetPivotWhiteBoxTest, OutputWriterTest, TestConfig, TestMacros, EchoParamsTest, SolrRequestParserTest, QueryEqualityTest, TestMissingGroups, InfoHandlerTest, CdcrUpdateLogTest, TestDistribIDF, TestCrossCoreJoin, TestRandomRequestDistribution, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [junit4] Completed [285/566 (1!)] on J0 in 334.85s, 1 test, 1 error <<< FAILURES! [...truncated 919 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728: 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:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1436: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:991: There were test failures: 566 suites (8 ignored), 2245 tests, 1 error, 33 ignored (18 assumptions) [seed: 1EF0D91F3A098E50] Total time: 200 minutes 23 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
