Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/927/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=47322, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=47322, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: java.lang.RuntimeException: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:32953, http://127.0.0.1:37053, http://127.0.0.1:44462, http://127.0.0.1:34808, http://127.0.0.1:44446] at __randomizedtesting.SeedInfo.seed([4C1214053B36905A]:0) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:896) Caused by: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[http://127.0.0.1:32953, http://127.0.0.1:37053, http://127.0.0.1:44462, http://127.0.0.1:34808, http://127.0.0.1:44446] at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:352) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:886) Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:37053: KeeperErrorCode = Session expired for /overseer/collection-queue-work/qnr- at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:576) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) ... 7 more FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler Error Message: ObjectTracker found 6 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] Stack Trace: java.lang.AssertionError: ObjectTracker found 6 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] at __randomizedtesting.SeedInfo.seed([4C1214053B36905A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228) at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 11311 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/init-core-data-001 [junit4] 2> 542411 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[4C1214053B36905A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 542411 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[4C1214053B36905A]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 542427 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 542427 INFO (Thread-45563) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 542427 INFO (Thread-45563) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 542527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkTestServer start zk server on port:56671 [junit4] 2> 542528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 542530 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 542540 INFO (zkCallback-271-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@632f902c name:ZooKeeperConnection Watcher:127.0.0.1:56671 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 542541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 542541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 542541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 542544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 542547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 542550 INFO (zkCallback-272-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3927d2a2 name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 542550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 542551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 542551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 542553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 542554 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 542555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 542556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 542561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542561 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 542563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 542564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 542565 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 542567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542567 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 542570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 542571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542571 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 542573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 542574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542574 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 542575 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542576 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 542803 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1 [junit4] 2> 542837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 542877 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e0908fd{/,null,AVAILABLE} [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1763fa08{HTTP/1.1}{127.0.0.1:44446} [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.Server Started @545782ms [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/tempDir-001/control/data, hostContext=/, hostPort=44446, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores} [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001' [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 542879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 542880 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 542884 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 542906 INFO (zkCallback-273-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c8decba name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 542906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 542907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 542913 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 542913 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/solr.xml [junit4] 2> 542918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores [junit4] 2> 542918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer New CoreContainer 295755103 [junit4] 2> 542918 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001] [junit4] 2> 542919 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/lib [junit4] 2> 542919 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 542920 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 542920 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 542921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 542921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56671/solr [junit4] 2> 542921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 542921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 542933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 542974 INFO (zkCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f81a45a name:ZooKeeperConnection Watcher:127.0.0.1:56671 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 542975 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 542976 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 542989 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 543008 INFO (zkCallback-277-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1284ea70 name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 543008 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 543010 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 543012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 543014 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 543015 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 543017 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 543018 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 543019 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 543020 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 543021 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 543021 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44446_ [junit4] 2> 543022 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44446_ [junit4] 2> 543023 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 543024 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 543024 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 543025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95344896464977924-127.0.0.1:44446_-n_0000000000 [junit4] 2> 543025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44446_ [junit4] 2> 543025 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 543026 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.Overseer Overseer (id=95344896464977924-127.0.0.1:44446_-n_0000000000) starting [junit4] 2> 543027 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 543040 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 543052 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 543053 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 543066 INFO (OverseerCollectionConfigSetProcessor-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 543092 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 543146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 543146 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 543150 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores [junit4] 2> 543154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 543154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1 [junit4] 2> 543154 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 543159 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 543160 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 543172 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 543191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 543191 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:44446_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 543192 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:44446", [junit4] 2> "node_name":"127.0.0.1:44446_", [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> 543193 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:44446", [junit4] 2> "node_name":"127.0.0.1:44446_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 543193 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 543194 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 543196 INFO (zkCallback-277-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 543202 INFO (zkCallback-277-thread-2-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 544175 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 544175 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 544175 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 544175 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 544176 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 544176 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1' [junit4] 2> 544176 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 544176 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 544177 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 544183 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 544188 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 544197 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 544206 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 544452 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 544458 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 544459 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 544467 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 544472 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 544474 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 544481 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 544511 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 544511 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 544511 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 544512 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/] [junit4] 2> 544512 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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@3bfa081b [junit4] 2> 544512 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data [junit4] 2> 544512 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/index/ [junit4] 2> 544512 WARN (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 544513 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/index [junit4] 2> 544513 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@78c8ea73 [junit4] 2> 544513 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@7ecc3029 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41c8dd9),segFN=segments_1,generation=1} [junit4] 2> 544513 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 544516 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 544516 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544517 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544523 WARN (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 544525 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 544525 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 544526 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/ [junit4] 2> 544526 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 544536 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 544538 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 544542 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 544543 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544551 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544551 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544552 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 544552 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 544554 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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=50, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=44.30078125, floorSegmentMB=2.1962890625, forceMergeDeletesPctAllowed=2.166015985407792, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14128460383885866 [junit4] 2> 544555 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(RAMDirectory@7ecc3029 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@41c8dd9),segFN=segments_1,generation=1} [junit4] 2> 544555 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 544555 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6b78d0c8[collection1] main] [junit4] 2> 544555 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544558 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544558 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544559 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 544560 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 544560 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544560 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544560 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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> 544560 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ 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 1525518345658433536 [junit4] 2> 544562 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1525518345658433536 [junit4] 2> 544562 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 544563 INFO (coreLoadExecutor-883-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 544567 INFO (searcherExecutor-884-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6b78d0c8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 544575 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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:44446 collection:control_collection shard:shard1 [junit4] 2> 544579 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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> 544582 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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/95344896464977924-core_node1-n_0000000000 [junit4] 2> 544582 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 544584 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 544584 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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> 544584 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44446/collection1/ [junit4] 2> 544584 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 544584 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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> 544584 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:44446/collection1/ has no replicas [junit4] 2> 544588 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 544590 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 544592 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:44446", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 544593 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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:44446/collection1/ shard1 [junit4] 2> 544693 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 544694 INFO (zkCallback-277-thread-2-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 544694 INFO (zkCallback-277-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 544714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 544734 INFO (zkCallback-279-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d20ac6c name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 544735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 544735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 544735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 544738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 544738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 544738 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 544744 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:44446/collection1/ and leader is http://127.0.0.1:44446/collection1/ [junit4] 2> 544744 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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:44446 [junit4] 2> 544744 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 544744 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 544744 INFO (coreZkRegister-877-thread-1-processing-n:127.0.0.1:44446_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:44446_ 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> 544746 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:44446", [junit4] 2> "node_name":"127.0.0.1:44446_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 544746 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 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:44446", [junit4] 2> "node_name":"127.0.0.1:44446_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 544754 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 544762 INFO (zkCallback-280-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@51ff2a9f name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 544762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 544762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 544765 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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: 2 [junit4] 2> 544765 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 544766 INFO (zkCallback-277-thread-2-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 544766 INFO (zkCallback-277-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 544766 INFO (zkCallback-279-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> 544870 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 545382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores/collection1 [junit4] 2> 545382 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001 [junit4] 2> 545384 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 545386 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d79d4e2{/,null,AVAILABLE} [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3779a706{HTTP/1.1}{127.0.0.1:37053} [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.e.j.s.Server Started @548290ms [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=37053, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores} [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001' [junit4] 2> 545387 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 545388 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 545388 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 545420 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 545501 INFO (zkCallback-281-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@15164a3d name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 545501 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 545502 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 545509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 545509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/solr.xml [junit4] 2> 545535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores [junit4] 2> 545535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer New CoreContainer 1405618217 [junit4] 2> 545535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001] [junit4] 2> 545535 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/lib [junit4] 2> 545536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] 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> 545537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 545537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 545538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 545538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56671/solr [junit4] 2> 545538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 545538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 545538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 545541 INFO (zkCallback-284-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4135e5e name:ZooKeeperConnection Watcher:127.0.0.1:56671 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 545541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 545541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 545552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 545562 INFO (zkCallback-285-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@991485e name:ZooKeeperConnection Watcher:127.0.0.1:56671/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 545562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 545568 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 546572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37053_ [junit4] 2> 546573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:37053_ [junit4] 2> 546574 INFO (zkCallback-277-thread-1-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 546574 INFO (zkCallback-277-thread-2-processing-n:127.0.0.1:44446_) [n:127.0.0.1:44446_ ] 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> 546574 INFO (zkCallback-285-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ ] 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> 546576 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 546576 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95344896464977929-127.0.0.1:37053_-n_0000000001 [junit4] 2> 546577 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95344896464977924-127.0.0.1:44446_-n_0000000000 to know if I could be the leader [junit4] 2> 546582 INFO (zkCallback-279-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> 546586 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 546588 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 546591 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores [junit4] 2> 546592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=collection1, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 546592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores/collection1 [junit4] 2> 546592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 546624 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 546624 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 546626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 546626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 546626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for [collection1] ver [0] [junit4] 2> 546626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 546627 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:37053", [junit4] 2> "node_name":"127.0.0.1:37053_", [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> 546627 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] 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:37053", [junit4] 2> "node_name":"127.0.0.1:37053_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 546627 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 546628 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 546628 INFO (OverseerStateUpdate-95344896464977924-127.0.0.1:44446_-n_0000000000) [n:127.0.0.1:44446_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 546629 INFO (zkCallback-285-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ ] 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> 546629 INFO (zkCallback-285-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ ] o.a.s.c.c.ZkStateReader Updating data for [collection1] from [0] to [1] [junit4] 2> 546641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 546641 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[4C1214053B36905A]) [n:127.0.0.1:37053_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 547626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 547626 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 547627 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 547627 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 547627 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 547627 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_4C1214053B36905A-001/shard-1-001/cores/collection1' [junit4] 2> 547628 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 547628 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 547629 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 547634 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 547658 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 547721 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 547728 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 548050 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 548061 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 548062 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 548118 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 548127 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 548129 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 548130 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 548131 INFO (coreLoadExecutor-894-thread-1-processing-n:127.0.0.1:37053_) [n:127.0.0.1:37053_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 5481 [...truncated too long message...] stReplicationHandler.doTestReplicateAfterCoreReload-seed#[4C1214053B36905A]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1dc736fe{/solr,null,UNAVAILABLE} [junit4] 2> 2819335 INFO (SUITE-TestReplicationHandler-seed#[4C1214053B36905A]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> ObjectTracker found 6 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException [junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42) [junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:351) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:386) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:270) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:387) [junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:275) [junit4] 2> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60), sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=es-MX, timezone=Europe/Andorra [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=64106512,total=525860864 [junit4] 2> NOTE: All tests run in this JVM: [TestFastOutputStream, IndexSchemaTest, TestPerFieldSimilarityWithDefaultOverride, TestRTGBase, TestFieldCollectionResource, TestBulkSchemaAPI, TestSolrDynamicMBean, HighlighterMaxOffsetTest, DocumentAnalysisRequestHandlerTest, TestDistribIDF, TestOmitPositions, TestBulkSchemaConcurrent, PathHierarchyTokenizerFactoryTest, AsyncCallRequestStatusResponseTest, TestAnalyzedSuggestions, TestConfig, TriLevelCompositeIdRoutingTest, DistributedFacetPivotSmallAdvancedTest, BlockDirectoryTest, ZkNodePropsTest, TestRangeQuery, ConfigSetsAPITest, BasicZkTest, TestMergePolicyConfig, ReplicationFactorTest, UUIDFieldTest, QueryElevationComponentTest, SignatureUpdateProcessorFactoryTest, TestBinaryField, DisMaxRequestHandlerTest, URLClassifyProcessorTest, StandardRequestHandlerTest, TestSmileRequest, JsonLoaderTest, TestChildDocTransformer, DocExpirationUpdateProcessorFactoryTest, TestConfigOverlay, DeleteShardTest, TestFunctionQuery, TestScoreJoinQPNoScore, LeaderInitiatedRecoveryOnCommitTest, TestReloadDeadlock, DistributedQueryElevationComponentTest, HdfsThreadLeakTest, HdfsCollectionsAPIDistributedZkTest, SuggestComponentTest, TestLMDirichletSimilarityFactory, TestPhraseSuggestions, QueryEqualityTest, FullHLLTest, PreAnalyzedUpdateProcessorTest, SharedFSAutoReplicaFailoverTest, ExitableDirectoryReaderTest, OverseerTaskQueueTest, TestLazyCores, TestPseudoReturnFields, JSONWriterTest, DocValuesMissingTest, TestLRUStatsCache, SystemInfoHandlerTest, TestAnalyzeInfixSuggestions, CloudExitableDirectoryReaderTest, TestShortCircuitedRequests, TestAddFieldRealTimeGet, TestHighlightDedupGrouping, TestManagedSchemaFieldTypeResource, TestFoldingMultitermQuery, SynonymTokenizerTest, DistanceUnitsTest, CurrencyFieldOpenExchangeTest, HdfsLockFactoryTest, TestAtomicUpdateErrorCases, TestLRUCache, TestStressUserVersions, TestUniqueKeyFieldResource, AnalysisAfterCoreReloadTest, PolyFieldTest, LeaderElectionIntegrationTest, CdcrVersionReplicationTest, ForceLeaderTest, RequiredFieldsTest, BasicDistributedZkTest, CloudMLTQParserTest, TestReplicaProperties, TestRemoteStreaming, DistribJoinFromCollectionTest, TestCSVLoader, TestBadConfig, TestLuceneMatchVersion, BlockJoinFacetRandomTest, DeleteInactiveReplicaTest, HdfsRecoveryZkTest, DistributedQueryComponentCustomSortTest, TestDistributedSearch, TestReplicationHandler] [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandler -Dtests.seed=4C1214053B36905A -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=es-MX -Dtests.timezone=Europe/Andorra -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | TestReplicationHandler (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 6 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] [junit4] > at __randomizedtesting.SeedInfo.seed([4C1214053B36905A]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [418/577 (2!)] on J0 in 750.03s, 13 tests, 1 failure <<< FAILURES! [...truncated 535 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 577 suites (8 ignored), 2318 tests, 1 suite-level error, 1 error, 33 ignored (18 assumptions) [seed: 4C1214053B36905A] Total time: 214 minutes 34 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org