Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/50/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=20956, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=20956, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45363/xhzm: collection already exists: awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([A06829637BC1E211]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1192) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:962) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:898) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=123387, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=123387, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38921/_yrh: collection already exists: awholynewstresscollection_collection2_1 at __randomizedtesting.SeedInfo.seed([A06829637BC1E211]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:404) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:357) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1192) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:962) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:898) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:987) FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler Error Message: ObjectTracker found 11 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] Stack Trace: java.lang.AssertionError: ObjectTracker found 11 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory] at __randomizedtesting.SeedInfo.seed([A06829637BC1E211]: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:256) at sun.reflect.GeneratedMethodAccessor22.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) 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 11623 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/init-core-data-001 [junit4] 2> 1249848 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A06829637BC1E211]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 1249848 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[A06829637BC1E211]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /xhzm/ [junit4] 2> 1249852 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1249852 INFO (Thread-13251) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1249852 INFO (Thread-13251) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1249952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkTestServer start zk server on port:49831 [junit4] 2> 1249953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1249954 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1249955 INFO (zkCallback-1558-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e9ddee8 name:ZooKeeperConnection Watcher:127.0.0.1:49831 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1249955 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1249955 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1249955 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1249957 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1249957 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1249958 INFO (zkCallback-1559-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a1af0d1 name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1249958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1249958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1249958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1249960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1249961 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1249962 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1249963 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1249963 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1249965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1249965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1249966 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1249966 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1249967 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1249967 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1249968 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1249968 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1249969 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1249970 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1249971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1249971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1249972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1249972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1249973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1249973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1249974 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1249974 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1249976 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1249976 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1250039 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1 [junit4] 2> 1250040 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1250041 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@79c3d35a{/xhzm,null,AVAILABLE} [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3cf3e23d{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/tempDir-001/control/data, hostContext=/xhzm, hostPort=45805, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores} [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001' [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1250050 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1250051 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1250051 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1250051 INFO (zkCallback-1560-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7487ba32 name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1250052 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1250052 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1250052 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1250052 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/solr.xml [junit4] 2> 1250056 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores [junit4] 2> 1250056 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer New CoreContainer 1806641505 [junit4] 2> 1250056 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001] [junit4] 2> 1250056 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/lib [junit4] 2> 1250056 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] 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> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49831/solr [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1250057 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1250058 INFO (zkCallback-1563-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@63d6b10a name:ZooKeeperConnection Watcher:127.0.0.1:49831 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1250058 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1250059 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1250061 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/1561110113@b6ca318 [junit4] 2> 1250061 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1250062 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58665241 name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1250062 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1250063 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1250064 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1250066 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1250067 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1250068 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1250071 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1250072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1250072 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1250073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1250073 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1250074 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1250076 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1250077 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1250077 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1250077 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95799072864075780-127.0.0.1:45805_xhzm-n_0000000000 [junit4] 2> 1250078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45805_xhzm [junit4] 2> 1250078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1250078 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.Overseer Overseer (id=95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) starting [junit4] 2> 1250079 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1250082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1250082 INFO (OverseerCollectionConfigSetProcessor-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1250082 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1250082 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45805_xhzm [junit4] 2> 1250090 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45805_xhzm [junit4] 2> 1250091 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1250091 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 1250094 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1250100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1250100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1250100 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores [junit4] 2> 1250101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] 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> 1250101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1 [junit4] 2> 1250101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1250102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 1250102 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45805_xhzm ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1250102 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1250102 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1250102 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 1250102 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 1250103 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 1250103 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45805/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45805_xhzm", [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> 1250103 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1250103 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45805/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45805_xhzm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1250104 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1250104 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1250105 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] 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> 1251103 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1' [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1251104 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1251105 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1251108 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1251111 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 1251118 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1251123 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1251212 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1251216 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1251217 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1251222 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1251223 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1251225 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1251225 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1251226 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1 [junit4] 2> 1251226 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1251226 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1251226 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/] [junit4] 2> 1251226 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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@7ec93a98 [junit4] 2> 1251227 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data [junit4] 2> 1251227 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/index/ [junit4] 2> 1251227 WARN (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1251227 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/index [junit4] 2> 1251227 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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=29, maxMergedSegmentMB=1.8359375, floorSegmentMB=1.4501953125, forceMergeDeletesPctAllowed=20.96224958701808, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6607549447692009 [junit4] 2> 1251228 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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@61015113 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8d3a315),segFN=segments_1,generation=1} [junit4] 2> 1251228 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1251228 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45805_xhzm 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-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/ [junit4] 2> 1251228 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251230 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251230 WARN (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251232 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1251232 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1251233 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1251233 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1251235 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 1251235 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251235 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251235 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.2294921875, noCFSRatio=0.42439390548904665] [junit4] 2> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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@61015113 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8d3a315),segFN=segments_1,generation=1} [junit4] 2> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1251236 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6530b99c[collection1] main] [junit4] 2> 1251237 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251239 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1251240 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1251240 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251240 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251240 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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> 1251240 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm 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 1532785167279587328 [junit4] 2> 1251241 INFO (searcherExecutor-4338-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6530b99c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1251242 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1532785167279587328 [junit4] 2> 1251242 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1251242 INFO (coreLoadExecutor-4337-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1251243 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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:45805/xhzm collection:control_collection shard:shard1 [junit4] 2> 1251243 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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> 1251245 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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/95799072864075780-core_node1-n_0000000000 [junit4] 2> 1251246 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:45805/xhzm/collection1/ [junit4] 2> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1251247 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:45805/xhzm/collection1/ has no replicas [junit4] 2> 1251247 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1251248 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95799072864075780-core_node1-n_0000000000 [junit4] 2> 1251249 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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:45805/xhzm/collection1/ shard1 [junit4] 2> 1251250 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45805/xhzm";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1251351 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] 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> 1251400 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:45805/xhzm/collection1/ and leader is http://127.0.0.1:45805/xhzm/collection1/ [junit4] 2> 1251400 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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:45805/xhzm [junit4] 2> 1251400 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1251400 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1251400 INFO (coreZkRegister-4331-thread-1-processing-n:127.0.0.1:45805_xhzm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:45805_xhzm 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> 1251402 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45805/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45805_xhzm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1251402 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:45805/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45805_xhzm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1251504 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] 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> 1251603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.s.i.CloudSolrClient Final constructed zkHost string: 127.0.0.1:49831/solr [junit4] 2> 1251603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1251603 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1251604 INFO (zkCallback-1566-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@262b3cf2 name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1251604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1251604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1251604 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1251605 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1251606 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1251679 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/cores/collection1 [junit4] 2> 1251680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001 [junit4] 2> 1251680 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 1251687 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b6a640a{/xhzm,null,AVAILABLE} [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1b0b6f7b{HTTP/1.1,[http/1.1]}{127.0.0.1:?????} [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.e.j.s.Server Started @???????ms [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/xhzm, hostPort=45363, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/cores} [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001' [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1251688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1251693 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1251694 INFO (zkCallback-1567-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@401fe75d name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1251695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1251695 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1251703 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1251703 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/solr.xml [junit4] 2> 1251706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/cores [junit4] 2> 1251706 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer New CoreContainer 184594453 [junit4] 2> 1251707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001] [junit4] 2> 1251707 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/lib [junit4] 2> 1251707 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] 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> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49831/solr [junit4] 2> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1251708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1251709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1251709 INFO (zkCallback-1570-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29d075a9 name:ZooKeeperConnection Watcher:127.0.0.1:49831 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1251710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1251710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1251710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$10/1561110113@4594136c [junit4] 2> 1251711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1251712 INFO (zkCallback-1571-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@74560db6 name:ZooKeeperConnection Watcher:127.0.0.1:49831/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1251712 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1251717 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1251718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1251720 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1251720 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95799072864075784-127.0.0.1:45363_xhzm-n_0000000001 [junit4] 2> 1251721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95799072864075780-127.0.0.1:45805_xhzm-n_0000000000 to know if I could be the leader [junit4] 2> 1251721 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.ZkController Publish node=127.0.0.1:45363_xhzm as DOWN [junit4] 2> 1251722 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:45363_xhzm"} current state version: 3 [junit4] 2> 1251722 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:45363_xhzm [junit4] 2> 1251824 INFO (zkCallback-1566-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> 1251824 INFO (zkCallback-1571-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm ] 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> 1251824 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] 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> 1252723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45363_xhzm [junit4] 2> 1252723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:45363_xhzm [junit4] 2> 1252724 INFO (zkCallback-1566-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> 1252724 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] 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> 1252724 INFO (zkCallback-1571-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm ] 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> 1252724 INFO (zkCallback-1566-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1252724 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1252724 INFO (zkCallback-1571-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 1252732 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1252732 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1252733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/cores [junit4] 2> 1252735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] 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> 1252735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E211-001/shard-1-001/cores/collection1 [junit4] 2> 1252735 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1252745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1 [junit4] 2> 1252745 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[A06829637BC1E211]) [n:127.0.0.1:45363_xhzm ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1252749 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1252749 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1252749 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController Collection collection1 not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 1252749 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [collection1] [junit4] 2> 1252749 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45363/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45363_xhzm", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 1252750 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] 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:45363/xhzm";, [junit4] 2> "node_name":"127.0.0.1:45363_xhzm", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1252750 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1252750 INFO (OverseerStateUpdate-95799072864075780-127.0.0.1:45805_xhzm-n_0000000000) [n:127.0.0.1:45805_xhzm ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1252750 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [collection1] [junit4] 2> 1252750 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1252851 INFO (zkCallback-1566-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 1252851 INFO (zkCallback-1564-thread-1-processing-n:127.0.0.1:45805_xhzm) [n:127.0.0.1:45805_xhzm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 1252851 INFO (zkCallback-1571-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred - updating... (live nodes size: [2]) [junit4] 2> 1253751 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1253751 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1253751 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1253751 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 1253752 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1253752 INFO (coreLoadExecutor-4348-thread-1-processing-n:127.0.0.1:45363_xhzm) [n:127.0.0.1:45363_xhzm c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_A06829637BC1E2 [...truncated too long message...] wnClass(HdfsTestUtil.java:198) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:52) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 54 more [junit4] 2> 4153968 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[A06829637BC1E211]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Apr 27, 2016 6:12:29 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 3 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=FSTOrd50, range_facet_l_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=FSTOrd50, multiDefault=Lucene50(blocksize=128), a_t=Lucene50(blocksize=128), intDefault=FSTOrd50, a_i1=Lucene50(blocksize=128), id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=Lucene50(blocksize=128), text=PostingsFormat(name=MockRandom), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=549, maxMBSortInHeap=6.8592995711028895, sim=ClassicSimilarity, locale=el-CY, timezone=America/Nipigon [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=2,free=71011048,total=471334912 [junit4] 2> NOTE: All tests run in this JVM: [TestExactStatsCache, TestManagedResource, HdfsChaosMonkeySafeLeaderTest, TestOnReconnectListenerSupport, BaseCdcrDistributedZkTest, TestJmxMonitoredMap, TestNRTOpen, CleanupOldIndexTest, TestConfigSetsAPIExclusivity, DirectUpdateHandlerTest, TestReloadDeadlock, SampleTest, TestReversedWildcardFilterFactory, TestAuthenticationFramework, TermVectorComponentDistributedTest, TestDocBasedVersionConstraints, TestMiniSolrCloudClusterBase, StatsComponentTest, BlobRepositoryCloudTest, HdfsBasicDistributedZk2Test, ConnectionManagerTest, TestDefaultStatsCache, TestConfigSetProperties, TestRuleBasedAuthorizationPlugin, TestSweetSpotSimilarityFactory, TestRecovery, TestStressLiveNodes, TestPerFieldSimilarityClassic, SolrCoreCheckLockOnStartupTest, TestSort, BigEndianAscendingWordSerializerTest, TestClusterStateMutator, RequestLoggingTest, CoreAdminCreateDiscoverTest, SimpleCollectionCreateDeleteTest, BlockJoinFacetDistribTest, SearchHandlerTest, TestSearchPerf, HardAutoCommitTest, ClusterStateTest, ShardSplitTest, CdcrRequestHandlerTest, AnalyticsMergeStrategyTest, TestFileDictionaryLookup, PKIAuthenticationIntegrationTest, OverseerStatusTest, TestSolrJ, TestZkChroot, TestSolrQueryParserDefaultOperatorResource, DeleteInactiveReplicaTest, DistributedFacetPivotWhiteBoxTest, CurrencyFieldXmlFileTest, TestStressRecovery, DeleteShardTest, XmlUpdateRequestHandlerTest, TestCloudManagedSchema, TestExtendedDismaxParser, TestComplexPhraseQParserPlugin, TestSuggestSpellingConverter, TestMaxScoreQueryParser, TestIndexingPerformance, HdfsRecoverLeaseTest, TestReload, TestExpandComponent, IgnoreCommitOptimizeUpdateProcessorFactoryTest, GraphQueryTest, DistributedSuggestComponentTest, LeaderElectionTest, QueryParsingTest, TestPostingsSolrHighlighter, TestJmxIntegration, TestOrdValues, TestCSVResponseWriter, ShowFileRequestHandlerTest, TestSolrQueryParser, TestIntervalFaceting, TestSolrDeletionPolicy1, TestShortCircuitedRequests, RollingRestartTest, TestSmileRequest, TestXmlQParser, HighlighterConfigTest, StatelessScriptUpdateProcessorFactoryTest, HdfsDirectoryTest, AnalysisErrorHandlingTest, TestDefaultSearchFieldResource, TestImplicitCoreProperties, TestBulkSchemaAPI, SuggestComponentContextFilterQueryTest, BasicDistributedZk2Test, BinaryUpdateRequestHandlerTest, TestGroupingSearch, TestWriterPerf, OverriddenZkACLAndCredentialsProvidersTest, JavabinLoaderTest, SimpleFacetsTest, RegexBoostProcessorTest, UnloadDistributedZkTest, SyncSliceTest, OverseerTest, LeaderElectionIntegrationTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, ZkSolrClientTest, ZkCLITest, ShardRoutingCustomTest, AutoCommitTest, TestStressReorder, TestJoin, TestCoreContainer, TestSolr4Spatial, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, DocValuesTest, SortByFunctionTest, DocValuesMultiTest, SolrInfoMBeanTest, DebugComponentTest, LukeRequestHandlerTest, DirectSolrSpellCheckerTest, TestQueryTypes, TestOmitPositions, RequestHandlersTest, RequiredFieldsTest, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, MBeansHandlerTest, TestPHPSerializedResponseWriter, TestBinaryResponseWriter, UpdateParamsTest, TestSolrIndexConfig, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, SynonymTokenizerTest, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, TestSystemIdResolver, PrimUtilsTest, DOMUtilTest, TestUtils, SliceStateTest, UUIDFieldTest, CircularListTest, TestRTGBase, DistributedIntervalFacetingTest, CdcrReplicationHandlerTest, DistribDocExpirationUpdateProcessorTest, ForceLeaderTest, LeaderInitiatedRecoveryOnShardRestartTest, ReplicationFactorTest, RestartWhileUpdatingTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [481/591 (3!)] on J2 in 583.49s, 1 test, 1 error <<< FAILURES! [...truncated 349 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 591 suites (10 ignored), 2382 tests, 1 suite-level error, 2 errors, 31 ignored (16 assumptions) [seed: A06829637BC1E211] Total time: 228 minutes 33 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: [email protected] For additional commands, e-mail: [email protected]
