Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/783/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=12089, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=12089, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33597/xmmr/y: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([7054EE2856A2078B]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=13262, name=collection0, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13262, name=collection0, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45637: Could not find collection : awholynewstresscollection_collection0_0 at __randomizedtesting.SeedInfo.seed([7054EE2856A2078B]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Build Log: [...truncated 10399 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/init-core-data-001 [junit4] 2> 1910012 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[7054EE2856A2078B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1910013 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[7054EE2856A2078B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xmmr/y [junit4] 2> 1910019 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1910019 INFO (Thread-7846) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1910021 INFO (Thread-7846) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1910119 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkTestServer start zk server on port:48212 [junit4] 2> 1910119 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1910120 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1910124 INFO (zkCallback-612-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5806a1ba name:ZooKeeperConnection Watcher:127.0.0.1:48212 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1910124 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1910124 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1910124 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1910129 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1910130 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1910134 INFO (zkCallback-613-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2ba0cb56 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1910134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1910134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1910134 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1910138 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1910140 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1910142 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1910146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1910150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910151 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1910156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910156 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1910159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910159 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1910162 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910163 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1910165 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910166 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1910169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910169 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1910172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910172 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1910176 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910177 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1910181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910181 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1910184 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910185 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1910596 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1 [junit4] 2> 1910600 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1910603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b86104{/xmmr/y,null,AVAILABLE} [junit4] 2> 1910609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3946260c{HTTP/1.1}{127.0.0.1:35971} [junit4] 2> 1910609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.Server Started @1914150ms [junit4] 2> 1910609 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/tempDir-001/control/data, hostContext=/xmmr/y, hostPort=35971, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores} [junit4] 2> 1910610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1910610 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/' [junit4] 2> 1910627 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1910629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1910640 INFO (zkCallback-614-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c48636a name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1910641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1910641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1910648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1910648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/solr.xml [junit4] 2> 1910653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores [junit4] 2> 1910653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer New CoreContainer 1556268113 [junit4] 2> 1910653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/] [junit4] 2> 1910653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/lib [junit4] 2> 1910653 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/lib). [junit4] 2> 1910678 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1910679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1910680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1910682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1910682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48212/solr [junit4] 2> 1910682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1910682 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1910683 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1910690 INFO (zkCallback-616-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ba210bf name:ZooKeeperConnection Watcher:127.0.0.1:48212 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1910691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1910691 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1910698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1910699 INFO (zkCallback-617-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@431da9f5 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1910700 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1910702 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1910706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1910710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1910712 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1910716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1910723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1910725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1910731 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1910734 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1910741 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35971_xmmr%2Fy [junit4] 2> 1910742 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35971_xmmr%2Fy [junit4] 2> 1910752 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1910754 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1910755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1910756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000 [junit4] 2> 1910756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35971_xmmr%2Fy [junit4] 2> 1910756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1910757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.Overseer Overseer (id=94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) starting [junit4] 2> 1910759 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1910770 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1910771 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1910771 INFO (OverseerCollectionConfigSetProcessor-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1910771 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1910794 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1910795 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1910795 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores [junit4] 2> 1910796 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1910797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/ [junit4] 2> 1910797 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1910799 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1910800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1910801 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1910801 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1910809 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:35971/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:35971_xmmr%2Fy", [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> 1910809 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:35971/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:35971_xmmr%2Fy", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1910810 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1910810 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1910811 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1910812 INFO (zkCallback-617-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy ] 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> 1911812 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1911813 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1911813 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1911813 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1911818 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1911818 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/' [junit4] 2> 1911837 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1911845 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1911849 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1911860 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1911862 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1911866 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1911970 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1911975 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1911976 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1911988 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1911990 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1911993 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1911994 WARN (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1911994 WARN (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1911994 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1911995 WARN (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1911995 WARN (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1911996 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1911996 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1911996 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1911997 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1911997 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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@299d5ac0 [junit4] 2> 1911997 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data [junit4] 2> 1911998 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data/index/ [junit4] 2> 1911998 WARN (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1911998 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data/index [junit4] 2> 1911998 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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=46, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=82.640625, floorSegmentMB=1.8916015625, forceMergeDeletesPctAllowed=29.841714198289385, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7519293534578809 [junit4] 2> 1911999 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@52164bb7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b108073),segFN=segments_1,generation=1} [junit4] 2> 1911999 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1911999 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data/ [junit4] 2> 1912000 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1912004 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1912004 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1912004 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1912005 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1912005 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1912005 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912007 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912007 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912007 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912010 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1912011 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1912012 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1912014 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1912018 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/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> 1912018 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912019 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912020 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912020 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1912021 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1912021 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1912022 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@52164bb7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2b108073),segFN=segments_1,generation=1} [junit4] 2> 1912022 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1912022 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@5fad063b[collection1] main [junit4] 2> 1912022 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912025 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912025 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912026 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1912026 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1912026 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912027 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912027 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912027 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1912027 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1912028 INFO (searcherExecutor-3327-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5fad063b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1912028 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912028 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912028 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912028 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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 1511196401606852608 [junit4] 2> 1912030 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy 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 1511196401606852608 [junit4] 2> 1912030 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1912031 INFO (coreLoadExecutor-3326-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1912032 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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:35971/xmmr/y collection:control_collection shard:shard1 [junit4] 2> 1912032 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912037 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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/94449774969159684-core_node1-n_0000000000 [junit4] 2> 1912040 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1912043 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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> 1912043 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1912044 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912044 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35971/xmmr/y/collection1/ [junit4] 2> 1912044 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1912044 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:35971/xmmr/y/collection1/ has no replicas [junit4] 2> 1912044 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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:35971/xmmr/y/collection1/ shard1 [junit4] 2> 1912045 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1912050 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1912054 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:35971/xmmr/y";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1912156 INFO (zkCallback-617-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy ] 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> 1912206 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:35971/xmmr/y/collection1/ and leader is http://127.0.0.1:35971/xmmr/y/collection1/ [junit4] 2> 1912206 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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:35971/xmmr/y [junit4] 2> 1912207 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1912207 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1912207 INFO (coreZkRegister-3320-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35971_xmmr%2Fy 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> 1912209 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:35971/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:35971_xmmr%2Fy", [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> 1912210 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:35971/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:35971_xmmr%2Fy", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1912301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1912303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1912304 INFO (zkCallback-619-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5dcfc788 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1912304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1912304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1912304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1912306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1912306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1912307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1912307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1912310 INFO (zkCallback-620-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7c1bd140 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1912311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1912311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1912312 INFO (zkCallback-617-thread-1-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy ] 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> 1912313 INFO (zkCallback-619-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1912326 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1912326 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1912432 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1912711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores/collection1 [junit4] 2> 1912712 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001 [junit4] 2> 1912712 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1912716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@39149dc3{/xmmr/y,null,AVAILABLE} [junit4] 2> 1912716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@49e9c363{HTTP/1.1}{127.0.0.1:59987} [junit4] 2> 1912716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.e.j.s.Server Started @1916257ms [junit4] 2> 1912716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/xmmr/y, hostPort=59987, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores} [junit4] 2> 1912717 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1912717 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/' [junit4] 2> 1912732 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1912738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1912738 INFO (zkCallback-621-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34cd20e3 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1912738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1912738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1912740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1912740 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/solr.xml [junit4] 2> 1912745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores [junit4] 2> 1912745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer New CoreContainer 578612396 [junit4] 2> 1912745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/] [junit4] 2> 1912746 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/lib [junit4] 2> 1912746 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/lib). [junit4] 2> 1912753 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] 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> 1912754 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1912755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1912755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1912755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:48212/solr [junit4] 2> 1912755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1912755 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1912757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1912759 INFO (zkCallback-623-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@66cce05b name:ZooKeeperConnection Watcher:127.0.0.1:48212 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1912761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1912761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1912766 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1912768 INFO (zkCallback-624-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@494939d3 name:ZooKeeperConnection Watcher:127.0.0.1:48212/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1912768 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1912785 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1913804 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59987_xmmr%2Fy [junit4] 2> 1913804 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:59987_xmmr%2Fy [junit4] 2> 1913807 INFO (zkCallback-617-thread-2-processing-n:127.0.0.1:35971_xmmr%2Fy) [n:127.0.0.1:35971_xmmr%2Fy ] 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> 1913807 INFO (zkCallback-624-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy ] 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> 1913807 INFO (zkCallback-619-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> 1913810 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1913812 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94449774969159689-127.0.0.1:59987_xmmr%2Fy-n_0000000001 [junit4] 2> 1913812 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000 to know if I could be the leader [junit4] 2> 1913826 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1913826 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1913826 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores [junit4] 2> 1913827 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1913827 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores/collection1/ [junit4] 2> 1913827 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1913829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0 [junit4] 2> 1913829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[7054EE2856A2078B]) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1913831 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1913832 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1913835 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:59987/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:59987_xmmr%2Fy", [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> 1913835 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 1913835 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 1913835 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] 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:59987/xmmr/y";, [junit4] 2> "node_name":"127.0.0.1:59987_xmmr%2Fy", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1913835 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1913836 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for collection1 ver 0 [junit4] 2> 1913836 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1913836 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1913939 INFO (OverseerStateUpdate-94449774969159684-127.0.0.1:35971_xmmr%2Fy-n_0000000000) [n:127.0.0.1:35971_xmmr%2Fy ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1913939 INFO (zkCallback-624-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 1913940 INFO (zkCallback-624-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy ] o.a.s.c.c.ZkStateReader Updating data for collection1 from 0 to 1 [junit4] 2> 1914836 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1914836 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1914837 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1914837 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1914839 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1914839 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.CollectionsAPIDistributedZkTest_7054EE2856A2078B-001/shard-1-001/cores/collection1/' [junit4] 2> 1914872 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1914879 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1914883 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1914891 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1914892 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1914897 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1914987 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1914992 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1914993 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1915001 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1915003 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1915005 INFO (coreLoadExecutor-3337-thread-1-processing-n:127.0.0.1:59987_xmmr%2Fy) [n:127.0.0.1:59987_xmmr%2Fy c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1915005 WARN (coreLoadExecutor-3337-thread-1-processing-n:127.0 [...truncated too long message...] ceAdapter.getMetrics(MetricsSourceAdapter.java:195) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 2382820 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[7054EE2856A2078B]-worker) [n:127.0.0.1:45637_ c:addReplicaColl s:shard2 r:core_node6 x:addReplicaColl_shard2_replica3] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Sep 02, 2015 6:27:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=PostingsFormat(name=Asserting), range_facet_l_dv=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=Direct), a_t=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=Asserting), a_i1=PostingsFormat(name=Asserting), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_i_dv=PostingsFormat(name=Asserting), text=PostingsFormat(name=MockRandom), range_facet_l=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=no_NO, timezone=Pacific/Wallis [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=2,free=149442480,total=535822336 [junit4] 2> NOTE: All tests run in this JVM: [TestSolrIndexConfig, DocValuesMultiTest, TestReversedWildcardFilterFactory, BinaryUpdateRequestHandlerTest, RequestHandlersTest, TestRandomRequestDistribution, TestFieldSortValues, ResponseHeaderTest, TestFieldTypeCollectionResource, UpdateParamsTest, DebugComponentTest, CdcrVersionReplicationTest, TestManagedStopFilterFactory, TestReload, TestOrdValues, HdfsThreadLeakTest, RegexBoostProcessorTest, TestSolrCLIRunExample, TestIndexSearcher, TestCollectionAPI, TestDefaultSimilarityFactory, TestCollapseQParserPlugin, TestReRankQParserPlugin, TestDynamicFieldCollectionResource, BigEndianAscendingWordDeserializerTest, TestDocBasedVersionConstraints, FullSolrCloudDistribCmdsTest, SystemInfoHandlerTest, SampleTest, AssignTest, CursorMarkTest, MigrateRouteKeyTest, HdfsDirectoryFactoryTest, TestDefaultSearchFieldResource, AnalyticsMergeStrategyTest, TestSolrCloudWithKerberosAlt, CdcrReplicationDistributedZkTest, TestSmileRequest, TestNamedUpdateProcessors, TestSolr4Spatial2, TestRecovery, DirectUpdateHandlerTest, StatsComponentTest, TestFuzzyAnalyzedSuggestions, CloudMLTQParserTest, TestStressReorder, TestFieldResource, TestSweetSpotSimilarityFactory, TestTrieFacet, TestCollationField, SuggesterTest, SegmentsInfoRequestHandlerTest, DistributedDebugComponentTest, SimpleFacetsTest, TestMinMaxOnMultiValuedField, TestScoreJoinQPNoScore, ExplicitHLLTest, LeaderElectionTest, LukeRequestHandlerTest, OverseerCollectionConfigSetProcessorTest, HighlighterConfigTest, QueryParsingTest, DistributedFacetPivotSmallAdvancedTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [246/543] on J2 in 1199.19s, 1 test, 1 error <<< FAILURES! [...truncated 934 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: 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:514: 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: 543 suites, 2146 tests, 2 errors, 29 ignored (17 assumptions) Total time: 196 minutes 30 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
