Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/874/
1 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=13020, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13020, 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:52750: collection already exists: awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([D79AC41903F460C5]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) Build Log: [...truncated 10368 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/init-core-data-001 [junit4] 2> 1144523 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D79AC41903F460C5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1144523 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D79AC41903F460C5]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1144535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1144535 INFO (Thread-8344) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1144535 INFO (Thread-8344) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1144635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkTestServer start zk server on port:42200 [junit4] 2> 1144635 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1144636 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1144638 INFO (zkCallback-1132-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7975ca98 name:ZooKeeperConnection Watcher:127.0.0.1:42200 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1144638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1144638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1144638 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1144642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1144642 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1144660 INFO (zkCallback-1133-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25336067 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1144660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1144660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1144660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1144662 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1144665 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1144666 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1144668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1144668 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1144670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1144670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1144672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1144672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1144674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1144674 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1144676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1144676 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1144677 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1144677 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1144678 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1144679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1144680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1144680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1144681 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1144682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1144683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1144683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1144685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1144685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1145023 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1 [junit4] 2> 1145025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1145026 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@44cc7e58{/,null,AVAILABLE} [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7a6ad6a5{HTTP/1.1,[http/1.1]}{127.0.0.1:40164} [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.Server Started @1148241ms [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/tempDir-001/control/data, hostContext=/, hostPort=40164, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores} [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001' [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1145027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1145028 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1145032 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1145033 INFO (zkCallback-1134-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64c75ac3 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1145033 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1145033 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1145034 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1145034 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/solr.xml [junit4] 2> 1145039 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores [junit4] 2> 1145039 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer New CoreContainer 227661634 [junit4] 2> 1145039 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001] [junit4] 2> 1145039 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/lib [junit4] 2> 1145040 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] 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> 1145042 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1145042 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1145042 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1145042 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42200/solr [junit4] 2> 1145042 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1145043 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1145043 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1145044 INFO (zkCallback-1136-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74d78773 name:ZooKeeperConnection Watcher:127.0.0.1:42200 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1145045 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1145045 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1145047 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1145049 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f667785 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1145049 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1145050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1145052 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1145056 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1145058 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1145060 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1145063 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1145065 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1145066 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1145067 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1145068 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40164_ [junit4] 2> 1145069 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:40164_ [junit4] 2> 1145070 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1145071 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1145072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1145073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94990819713286148-127.0.0.1:40164_-n_0000000000 [junit4] 2> 1145073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40164_ [junit4] 2> 1145073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1145074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.Overseer Overseer (id=94990819713286148-127.0.0.1:40164_-n_0000000000) starting [junit4] 2> 1145076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1145081 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1145081 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1145081 INFO (OverseerCollectionConfigSetProcessor-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1145082 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1145105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1145105 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1145106 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores [junit4] 2> 1145107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] 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> 1145107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1 [junit4] 2> 1145107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1145108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1145108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:40164_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1145109 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1145109 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1145110 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:40164", [junit4] 2> "node_name":"127.0.0.1:40164_", [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> 1145111 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1145111 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:40164", [junit4] 2> "node_name":"127.0.0.1:40164_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1145111 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1145111 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1145113 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] 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> 1146111 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1146111 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1' [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1146112 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1146114 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1146123 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1146127 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1146135 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1146141 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1146248 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1146270 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1146271 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1146282 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1146284 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1146285 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1146286 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1146286 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1146287 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1146287 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1146287 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1146288 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1146288 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1146288 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1146288 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1], dataDir=[null] [junit4] 2> 1146289 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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@5a595f61 [junit4] 2> 1146289 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data [junit4] 2> 1146289 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data/index/ [junit4] 2> 1146289 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1146289 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data/index [junit4] 2> 1146290 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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=43, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=66.142578125, floorSegmentMB=1.455078125, forceMergeDeletesPctAllowed=29.567575441761708, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.25357201707665694 [junit4] 2> 1146290 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6940fd64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55aa31c2),segFN=segments_1,generation=1} [junit4] 2> 1146290 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1146291 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data/ [junit4] 2> 1146291 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146294 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146294 WARN (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146296 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1146297 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1146297 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1146298 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1146301 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1146301 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146302 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146302 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146302 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=45.3935546875, floorSegmentMB=0.5771484375, forceMergeDeletesPctAllowed=9.072947969992404, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8218034873123675 [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6940fd64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@55aa31c2),segFN=segments_1,generation=1} [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@45783dbc[collection1] main [junit4] 2> 1146303 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146304 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146304 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146304 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1146304 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1146305 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146305 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146305 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146305 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1146305 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1146305 INFO (searcherExecutor-2377-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45783dbc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1146306 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146306 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146306 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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> 1146306 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ 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 1519853117250732032 [junit4] 2> 1146308 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1519853117250732032 [junit4] 2> 1146308 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1146308 INFO (coreLoadExecutor-2376-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1146308 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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:40164 collection:control_collection shard:shard1 [junit4] 2> 1146310 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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> 1146313 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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/94990819713286148-core_node1-n_0000000000 [junit4] 2> 1146319 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1146322 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1146323 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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> 1146323 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:40164/collection1/ [junit4] 2> 1146323 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1146323 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:40164/collection1/ has no replicas [junit4] 2> 1146323 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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> 1146325 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1146327 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1146328 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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:40164/collection1/ shard1 [junit4] 2> 1146329 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:40164", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1146431 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] 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> 1146479 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:40164/collection1/ and leader is http://127.0.0.1:40164/collection1/ [junit4] 2> 1146480 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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:40164 [junit4] 2> 1146480 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1146480 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1146480 INFO (coreZkRegister-2370-thread-1-processing-n:127.0.0.1:40164_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:40164_ 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> 1146481 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:40164", [junit4] 2> "node_name":"127.0.0.1:40164_", [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> 1146482 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:40164", [junit4] 2> "node_name":"127.0.0.1:40164_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1146584 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] 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> 1146610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1146611 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1146612 INFO (zkCallback-1139-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62d26535 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1146612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1146612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1146612 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1146614 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1146904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1 [junit4] 2> 1146905 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001 [junit4] 2> 1146906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1146907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f823f8e{/,null,AVAILABLE} [junit4] 2> 1146907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@140756fa{HTTP/1.1,[http/1.1]}{127.0.0.1:41579} [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.e.j.s.Server Started @1150121ms [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=41579, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores} [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001' [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1146908 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1146909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1146910 INFO (zkCallback-1140-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@564c3859 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1146910 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1146911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1146911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1146911 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/solr.xml [junit4] 2> 1146916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores [junit4] 2> 1146916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer New CoreContainer 1877587170 [junit4] 2> 1146916 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001] [junit4] 2> 1146916 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/lib [junit4] 2> 1146917 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] 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> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42200/solr [junit4] 2> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1146918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1146919 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1146920 INFO (zkCallback-1142-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ea9bb2a name:ZooKeeperConnection Watcher:127.0.0.1:42200 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1146923 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1146924 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1146939 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1146939 INFO (zkCallback-1143-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3cab6281 name:ZooKeeperConnection Watcher:127.0.0.1:42200/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1146939 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1146957 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1147967 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41579_ [junit4] 2> 1147968 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41579_ [junit4] 2> 1147968 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] 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> 1147968 INFO (zkCallback-1143-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ ] 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> 1147971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1147971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94990819713286152-127.0.0.1:41579_-n_0000000001 [junit4] 2> 1147972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94990819713286148-127.0.0.1:40164_-n_0000000000 to know if I could be the leader [junit4] 2> 1147976 INFO (zkCallback-1139-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> 1148005 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1148005 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1148005 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores [junit4] 2> 1148006 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] 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> 1148006 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1 [junit4] 2> 1148006 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1148007 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1148007 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1148008 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:41579", [junit4] 2> "node_name":"127.0.0.1:41579_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1148009 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] 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:41579", [junit4] 2> "node_name":"127.0.0.1:41579_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1148009 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1148009 INFO (OverseerStateUpdate-94990819713286148-127.0.0.1:40164_-n_0000000000) [n:127.0.0.1:40164_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1148010 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1148012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1148012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[D79AC41903F460C5]) [n:127.0.0.1:41579_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1148115 INFO (zkCallback-1137-thread-1-processing-n:127.0.0.1:40164_) [n:127.0.0.1:40164_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1148119 INFO (zkCallback-1139-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1148119 INFO (zkCallback-1143-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1149011 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1149011 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1149011 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1149011 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1149012 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1149012 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1' [junit4] 2> 1149012 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1149012 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1149013 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1149018 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1149022 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1149029 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1149035 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1149262 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1149270 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1149271 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1149279 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1149281 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1149282 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1149283 WARN (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1149283 WARN (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1149283 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1149283 WARN (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1149283 WARN (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1149315 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1149315 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 1149315 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1149316 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1], dataDir=[null] [junit4] 2> 1149316 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5a595f61 [junit4] 2> 1149316 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1/data [junit4] 2> 1149316 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 1149316 WARN (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1149317 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_D79AC41903F460C5-001/shard-1-001/cores/collection1/data/index [junit4] 2> 1149317 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=66.142578125, floorSegmentMB=1.455078125, forceMergeDeletesPctAllowed=29.567575441761708, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.25357201707665694 [junit4] 2> 1149317 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@35b731ff lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1991be32),segFN=segments_1,generation=1} [junit4] 2> 1149317 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1149321 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 1149322 INFO (coreLoadExecutor-2387-thread-1-processing-n:127.0.0.1:41579_) [n:127.0.0.1:41579_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateP [...truncated too long message...] llection already exists: awholynewstresscollection_collection2_0 [junit4] > at __randomizedtesting.SeedInfo.seed([D79AC41903F460C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13018, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:52750: Could not find collection : awholynewstresscollection_collection3_0 [junit4] > at __randomizedtesting.SeedInfo.seed([D79AC41903F460C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13015, 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:52750: collection already exists: awholynewstresscollection_collection0_0 [junit4] > at __randomizedtesting.SeedInfo.seed([D79AC41903F460C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881)Throwable #6: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13016, 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:52750: collection already exists: awholynewstresscollection_collection1_0 [junit4] > at __randomizedtesting.SeedInfo.seed([D79AC41903F460C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) [junit4] 2> 1497952 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[D79AC41903F460C5]-worker) [n:127.0.0.1:58189_ c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es, timezone=Africa/Harare [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=46221968,total=499646464 [junit4] 2> NOTE: All tests run in this JVM: [CollectionStateFormat2Test, TestCodecSupport, HdfsChaosMonkeyNothingIsSafeTest, SharedFSAutoReplicaFailoverTest, RequestLoggingTest, DistributedMLTComponentTest, LeaderInitiatedRecoveryOnShardRestartTest, DocValuesTest, TestLMJelinekMercerSimilarityFactory, TestCollapseQParserPlugin, TestLeaderElectionZkExpiry, TestDefaultSearchFieldResource, TestMiniSolrCloudClusterBase, TestSolr4Spatial2, TestQueryUtils, TestHashQParserPlugin, TestHashPartitioner, TestCollationFieldDocValues, FacetPivotSmallTest, SegmentsInfoRequestHandlerTest, TestQuerySenderListener, ChaosMonkeyNothingIsSafeTest, TestSolrConfigHandlerConcurrent, HLLUtilTest, CursorPagingTest, SolrPluginUtilsTest, AsyncMigrateRouteKeyTest, CollectionTooManyReplicasTest, TestPartialUpdateDeduplication, TestSystemIdResolver, CachingDirectoryFactoryTest, MoreLikeThisHandlerTest, MinimalSchemaTest, TestSolrConfigHandlerCloud, InfoHandlerTest, TestMacros, TestInitQParser, TestMiniSolrCloudClusterSSL, HighlighterTest, SolrIndexConfigTest, TestAddFieldRealTimeGet, TestRequestStatusCollectionAPI, TestConfigSetImmutable, CollectionsAPIDistributedZkTest] [junit4] Completed [188/564] on J1 in 354.27s, 1 test, 1 error <<< FAILURES! [...truncated 1186 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:789: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:726: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 564 suites (8 ignored), 2238 tests, 1 error, 33 ignored (18 assumptions) [seed: D79AC41903F460C5] Total time: 207 minutes 52 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