Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/969/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=1518, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1518, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38614: collection already exists: awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([91CC267C7A0257F]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) 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) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:970) Build Log: [...truncated 11266 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/init-core-data-001 [junit4] 2> 192422 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[91CC267C7A0257F]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 192422 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[91CC267C7A0257F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 192423 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 192424 INFO (Thread-252) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 192424 INFO (Thread-252) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 192524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkTestServer start zk server on port:49078 [junit4] 2> 192524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 192524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 192531 INFO (zkCallback-107-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@14cc11b3 name:ZooKeeperConnection Watcher:127.0.0.1:49078 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 192531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 192531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 192531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 192534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 192535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 192536 INFO (zkCallback-108-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@11bffa2f name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 192536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 192536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 192536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 192538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 192540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 192541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 192542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 192542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 192544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 192544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 192546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 192546 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 192547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 192548 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 192549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 192549 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 192550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 192551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 192552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 192552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 192553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 192553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 192555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 192555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 192556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 192556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 192560 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 192560 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 192661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1 [junit4] 2> 192663 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 192665 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@600e090b{/,null,AVAILABLE} [junit4] 2> 192668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7476a765{HTTP/1.1,[http/1.1]}{127.0.0.1:58615} [junit4] 2> 192668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.Server Started @195695ms [junit4] 2> 192668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/tempDir-001/control/data, hostContext=/, hostPort=58615, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores} [junit4] 2> 192669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 192669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001' [junit4] 2> 192669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 192669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 192669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 192670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 192673 INFO (zkCallback-109-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f89a99c name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 192674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 192674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 192676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 192676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/solr.xml [junit4] 2> 192685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores [junit4] 2> 192685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer New CoreContainer 90644678 [junit4] 2> 192685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001] [junit4] 2> 192686 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/lib [junit4] 2> 192686 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] 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> 192687 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 192688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 192688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 192688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49078/solr [junit4] 2> 192688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 192688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 192689 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 192690 INFO (zkCallback-112-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5137ffc2 name:ZooKeeperConnection Watcher:127.0.0.1:49078 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 192691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 192691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 192693 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 192694 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6f57ea4c name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 192695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 192696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 192701 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 192704 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 192706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 192708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 192710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 192711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 192713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 192714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 192715 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 192716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 192718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 192719 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 192720 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 192720 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95600332232982532-127.0.0.1:58615_-n_0000000000 [junit4] 2> 192721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58615_ [junit4] 2> 192721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 192722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.Overseer Overseer (id=95600332232982532-127.0.0.1:58615_-n_0000000000) starting [junit4] 2> 192723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 192728 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 192729 INFO (OverseerCollectionConfigSetProcessor-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 192729 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 192736 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58615_ [junit4] 2> 192737 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:58615_ [junit4] 2> 192737 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 192738 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 192740 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 192751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 192751 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 192752 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores [junit4] 2> 192753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] 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> 192753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1 [junit4] 2> 192753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 192756 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 192756 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 192756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1 [junit4] 2> 192756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:58615_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 192758 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:58615", [junit4] 2> "node_name":"127.0.0.1:58615_", [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> 192759 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 192759 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:58615", [junit4] 2> "node_name":"127.0.0.1:58615_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 192760 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 192760 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 192762 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] 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> 193759 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 193759 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 193760 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 193760 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 193760 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 193760 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1' [junit4] 2> 193761 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 193761 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 193762 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 193767 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 193770 WARN (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 193771 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 193780 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 193786 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 193869 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 193875 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 193876 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 193884 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 193887 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 193888 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 193889 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 193891 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1 [junit4] 2> 193891 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 193891 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 193892 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/] [junit4] 2> 193892 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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@19c2d51 [junit4] 2> 193893 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data [junit4] 2> 193893 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/index/ [junit4] 2> 193893 WARN (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 193893 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/index [junit4] 2> 193894 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=2, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2070742166344764] [junit4] 2> 193896 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fcf77da; maxCacheMB=0.4931640625 maxMergeSizeMB=0.28515625)),segFN=segments_1,generation=1} [junit4] 2> 193896 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 193896 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58615_ 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-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/ [junit4] 2> 193897 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 193900 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193901 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193901 WARN (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193903 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 193904 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 193905 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 193906 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 193909 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 193909 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193910 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193910 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193911 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 193911 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 193911 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=44.0009765625, floorSegmentMB=0.609375, forceMergeDeletesPctAllowed=26.86435737265088, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 193913 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(MMapDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/index-MMapDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fcf77da; maxCacheMB=0.4931640625 maxMergeSizeMB=0.28515625)),segFN=segments_1,generation=1} [junit4] 2> 193913 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 193913 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2a0fedaa[collection1] main] [junit4] 2> 193913 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193914 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 193915 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 193915 INFO (searcherExecutor-374-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2a0fedaa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 193915 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193916 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193916 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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> 193916 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ 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 1529605317291147264 [junit4] 2> 193917 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1529605317291147264 [junit4] 2> 193917 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 193918 INFO (coreLoadExecutor-373-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 193918 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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:58615 collection:control_collection shard:shard1 [junit4] 2> 193919 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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> 193922 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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/95600332232982532-core_node1-n_0000000000 [junit4] 2> 193922 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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> 193924 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 193924 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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> 193924 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58615/collection1/ [junit4] 2> 193924 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 193924 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:58615/collection1/ has no replicas [junit4] 2> 193924 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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> 193925 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 193927 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95600332232982532-core_node1-n_0000000000 [junit4] 2> 193929 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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:58615/collection1/ shard1 [junit4] 2> 193929 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:58615", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 194032 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] 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> 194080 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:58615/collection1/ and leader is http://127.0.0.1:58615/collection1/ [junit4] 2> 194080 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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:58615 [junit4] 2> 194080 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 194080 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 194080 INFO (coreZkRegister-367-thread-1-processing-n:127.0.0.1:58615_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:58615_ 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> 194082 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:58615", [junit4] 2> "node_name":"127.0.0.1:58615_", [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> 194082 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:58615", [junit4] 2> "node_name":"127.0.0.1:58615_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 194185 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] 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> 194258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 194259 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 194263 INFO (zkCallback-115-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e6d43eb name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 194263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 194263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 194264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 194267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 194270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 194270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 194270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 194270 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 194273 INFO (zkCallback-116-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@72ee1211 name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 194273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 194273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 194286 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 194286 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 194377 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores/collection1 [junit4] 2> 194378 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001 [junit4] 2> 194378 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 194380 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@397a3687{/,null,AVAILABLE} [junit4] 2> 194381 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@d87ca23{HTTP/1.1,[http/1.1]}{127.0.0.1:44184} [junit4] 2> 194381 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.e.j.s.Server Started @197409ms [junit4] 2> 194381 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=44184, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores} [junit4] 2> 194382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 194382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001' [junit4] 2> 194382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 194382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 194382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 194383 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 194385 INFO (zkCallback-117-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@629a0c1 name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 194385 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 194385 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 194387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 194387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/solr.xml [junit4] 2> 194392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores [junit4] 2> 194392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer New CoreContainer 1942581595 [junit4] 2> 194392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001] [junit4] 2> 194392 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/lib [junit4] 2> 194392 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 194392 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] 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> 194393 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49078/solr [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 194394 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 194396 INFO (zkCallback-120-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a190cd8 name:ZooKeeperConnection Watcher:127.0.0.1:49078 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 194396 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 194397 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 194399 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 194400 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@134dbfa2 name:ZooKeeperConnection Watcher:127.0.0.1:49078/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 194400 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 194405 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 194406 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 194407 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 194408 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95600332232982537-127.0.0.1:44184_-n_0000000001 [junit4] 2> 194408 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95600332232982532-127.0.0.1:58615_-n_0000000000 to know if I could be the leader [junit4] 2> 194408 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.ZkController Publish node=127.0.0.1:44184_ as DOWN [junit4] 2> 194410 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:44184_"} current state version: 3 [junit4] 2> 194410 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44184_ [junit4] 2> 194411 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 194514 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] 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> 194514 INFO (zkCallback-115-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> 194514 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] 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> 195413 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44184_ [junit4] 2> 195414 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44184_ [junit4] 2> 195415 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] 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> 195415 INFO (zkCallback-115-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> 195416 INFO (zkCallback-115-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 195416 INFO (zkCallback-113-thread-1-processing-n:127.0.0.1:58615_) [n:127.0.0.1:58615_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 195416 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] 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> 195420 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 195426 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 195426 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 195426 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores [junit4] 2> 195427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] 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> 195427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores/collection1 [junit4] 2> 195427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 195428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1 [junit4] 2> 195428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[91CC267C7A0257F]) [n:127.0.0.1:44184_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 195429 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 195429 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 195430 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 195430 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 195430 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [1] [junit4] 2> 195430 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:44184", [junit4] 2> "node_name":"127.0.0.1:44184_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 195431 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 195431 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] 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:44184", [junit4] 2> "node_name":"127.0.0.1:44184_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 195431 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 195431 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 195533 INFO (OverseerStateUpdate-95600332232982532-127.0.0.1:58615_-n_0000000000) [n:127.0.0.1:58615_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 195534 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 195534 INFO (zkCallback-121-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [1] to [2] [junit4] 2> 196431 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 196431 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 196432 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 196432 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 196433 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 196433 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores/collection1' [junit4] 2> 196433 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 196433 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 196434 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 196440 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 196444 WARN (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 196445 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 [junit4] 2> 196454 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 196459 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 196543 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 196548 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 196549 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 196559 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 196561 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 196563 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 196563 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 196569 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ 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-master/solr/build/solr-core/test/J1 [junit4] 2> 196569 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 196569 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 196570 INFO (coreLoadExecutor-384-thread-1-processing-n:127.0.0.1:44184_) [n:127.0.0.1:44184_ 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-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_91CC267C7A0257F-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPI [...truncated too long message...] owable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1514, 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:38614: collection already exists: awholynewstresscollection_collection1_0 [junit4] > at __randomizedtesting.SeedInfo.seed([91CC267C7A0257F]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:970)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1515, 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:38614: collection already exists: awholynewstresscollection_collection2_0 [junit4] > at __randomizedtesting.SeedInfo.seed([91CC267C7A0257F]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:970)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1513, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38614: collection already exists: awholynewstresscollection_collection0_0 [junit4] > at __randomizedtesting.SeedInfo.seed([91CC267C7A0257F]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:970)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1517, name=collection4, 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:38614: Could not find collection : awholynewstresscollection_collection4_0 [junit4] > at __randomizedtesting.SeedInfo.seed([91CC267C7A0257F]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [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:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:977) [junit4] 2> 633299 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[91CC267C7A0257F]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l_dv=PostingsFormat(name=LuceneFixedGap), _version_=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=FST50, a_t=FST50, intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), a_i1=FST50, id=PostingsFormat(name=LuceneFixedGap), range_facet_i_dv=FST50, text=PostingsFormat(name=Memory doPackFST= false), range_facet_l=FST50, timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1136, maxMBSortInHeap=5.692074336581147, sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ms, timezone=Asia/Rangoon [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=117973384,total=509083648 [junit4] 2> NOTE: All tests run in this JVM: [TestStressVersions, SortByFunctionTest, TestCloudPivotFacet, SparseHLLTest, CoreAdminCreateDiscoverTest, TestDistribDocBasedVersion, DistributedVersionInfoTest, SpatialFilterTest, CollectionsAPIDistributedZkTest] [junit4] Completed [74/581 (1!)] on J1 in 441.45s, 1 test, 1 error <<< FAILURES! [...truncated 1649 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/solr/common-build.xml:529: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/lucene/common-build.xml:1014: There were test failures: 581 suites (10 ignored), 2324 tests, 1 error, 32 ignored (16 assumptions) [seed: 91CC267C7A0257F] Total time: 214 minutes 32 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