Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/908/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=17222, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=17222, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56866/_jg/gr: collection already exists: awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([A0B1DC001F88A06F]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:574) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:240) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:229) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:807) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:881) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeyNothingIsSafeTest Error Message: ObjectTracker found 2 object(s) that were not released!!! [HdfsTransactionLog, HdfsTransactionLog] Stack Trace: java.lang.AssertionError: ObjectTracker found 2 object(s) that were not released!!! [HdfsTransactionLog, HdfsTransactionLog] at __randomizedtesting.SeedInfo.seed([A0B1DC001F88A06F]: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:229) at sun.reflect.GeneratedMethodAccessor19.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) 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) FAILED: junit.framework.TestSuite.org.apache.solr.cloud.HttpPartitionTest Error Message: ObjectTracker found 1 object(s) that were not released!!! [RawDirectoryWrapper] Stack Trace: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [RawDirectoryWrapper] at __randomizedtesting.SeedInfo.seed([A0B1DC001F88A06F]: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:229) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) 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:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) 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 10083 lines...] [junit4] Suite: org.apache.solr.cloud.HttpPartitionTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/init-core-data-001 [junit4] 2> 180382 INFO (SUITE-HttpPartitionTest-seed#[A0B1DC001F88A06F]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 180389 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 180394 INFO (Thread-5264) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 180394 INFO (Thread-5264) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 180494 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkTestServer start zk server on port:39851 [junit4] 2> 180494 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 180495 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 180498 INFO (zkCallback-212-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b19c3b4 name:ZooKeeperConnection Watcher:127.0.0.1:39851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 180498 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 180499 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 180499 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 180502 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 180506 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 180506 INFO (zkCallback-213-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68f34e02 name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 180506 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 180506 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 180506 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 180508 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 180509 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 180511 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 180513 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 180513 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 180515 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 180516 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 180517 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 180518 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 180519 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 180519 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 180520 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 180521 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 180522 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 180522 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 180524 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 180524 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 180525 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 180526 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 180527 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 180527 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 180529 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 180529 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 180531 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 180531 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 180869 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1 [junit4] 2> 180872 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 180878 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@298c814e{/,null,AVAILABLE} [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@21067650{HTTP/1.1}{127.0.0.1:54653} [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.Server Started @184428ms [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/tempDir-001/control/data, hostContext=/, hostPort=41694, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores} [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001' [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 180879 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 180880 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 180880 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 180882 INFO (zkCallback-214-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@127fb425 name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 180882 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 180883 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 180885 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 180886 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/solr.xml [junit4] 2> 180892 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores [junit4] 2> 180892 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer New CoreContainer 1616915773 [junit4] 2> 180892 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001] [junit4] 2> 180893 WARN (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/lib [junit4] 2> 180893 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] 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> 180895 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 180895 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 180895 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 180895 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39851/solr [junit4] 2> 180896 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 180896 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 180897 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 180898 INFO (zkCallback-217-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7ffd2c35 name:ZooKeeperConnection Watcher:127.0.0.1:39851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 180898 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 180899 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 180901 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 180902 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@a8bc984 name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 180902 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 180903 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 180905 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 180908 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 180910 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 180912 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 180914 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 180916 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 180917 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 180918 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 180919 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41694_ [junit4] 2> 180920 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:41694_ [junit4] 2> 180921 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 180923 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 180924 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 180924 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95189176525193220-127.0.0.1:41694_-n_0000000000 [junit4] 2> 180925 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:41694_ [junit4] 2> 180925 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 180926 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.Overseer Overseer (id=95189176525193220-127.0.0.1:41694_-n_0000000000) starting [junit4] 2> 180928 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 180935 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 180935 INFO (OverseerCollectionConfigSetProcessor-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 180935 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 180936 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 180959 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 180959 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 180960 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores [junit4] 2> 180961 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] 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> 180961 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1 [junit4] 2> 180961 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 180962 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 180963 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:41694_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 180963 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 180963 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 180965 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:41694";, [junit4] 2> "node_name":"127.0.0.1:41694_", [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> 180965 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 180966 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:41694";, [junit4] 2> "node_name":"127.0.0.1:41694_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 180966 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 180968 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 180969 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] 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> 181966 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 181966 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 181967 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 181967 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 181968 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 181968 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1' [junit4] 2> 181968 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 181968 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 181970 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 181976 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 181981 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 181989 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 181996 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 182097 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 182104 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 182105 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 182115 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 182118 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 182120 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 182121 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 182123 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 182123 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 182123 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 182124 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/] [junit4] 2> 182124 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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@2cc9a864 [junit4] 2> 182124 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data [junit4] 2> 182125 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/index/ [junit4] 2> 182125 WARN (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 182125 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/index [junit4] 2> 182125 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7250565167799969] [junit4] 2> 182126 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@643528d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@523e4f57),segFN=segments_1,generation=1} [junit4] 2> 182126 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 182134 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 182134 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 182134 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 182134 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 182135 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 182135 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182135 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182135 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182135 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182135 WARN (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182137 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 182137 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/ [junit4] 2> 182137 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 182141 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 182142 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 182143 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 182147 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 182148 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182148 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182148 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182149 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 182149 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 182149 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=0.87890625, floorSegmentMB=1.501953125, forceMergeDeletesPctAllowed=9.899249372184325, segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.27884502339917444 [junit4] 2> 182150 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@643528d9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@523e4f57),segFN=segments_1,generation=1} [junit4] 2> 182150 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 182150 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@71e0bfec[collection1] main] [junit4] 2> 182150 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182151 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182151 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182152 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 182152 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 182152 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182153 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182153 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182153 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 182153 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 182153 INFO (searcherExecutor-571-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@71e0bfec[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 182154 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182154 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182154 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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> 182154 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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 1523026826247536640 [junit4] 2> 182156 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ 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 1523026826247536640 [junit4] 2> 182156 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 182157 INFO (coreLoadExecutor-570-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 182157 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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:41694 collection:control_collection shard:shard1 [junit4] 2> 182158 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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> 182164 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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/95189176525193220-core_node1-n_0000000000 [junit4] 2> 182164 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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=10000 [junit4] 2> 182166 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 182166 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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> 182166 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:41694/collection1/ [junit4] 2> 182166 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 182166 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:41694/collection1/ has no replicas [junit4] 2> 182166 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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> 182167 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 182170 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 182172 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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:41694/collection1/ shard1 [junit4] 2> 182172 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:41694";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 182275 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] 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> 182323 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:41694/collection1/ and leader is http://127.0.0.1:41694/collection1/ [junit4] 2> 182324 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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:41694 [junit4] 2> 182324 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 182324 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 182324 INFO (coreZkRegister-564-thread-1-processing-n:127.0.0.1:41694_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:41694_ 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> 182326 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:41694";, [junit4] 2> "node_name":"127.0.0.1:41694_", [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> 182327 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:41694";, [junit4] 2> "node_name":"127.0.0.1:41694_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 182429 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] 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> 182466 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 182467 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 182468 INFO (zkCallback-220-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6d43fb79 name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 182468 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 182468 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 182468 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 182471 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 182794 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1 [junit4] 2> 182795 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001 [junit4] 2> 182797 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 182799 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@649ede26{/,null,AVAILABLE} [junit4] 2> 182799 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.ServerConnector Started ServerConnector@484666e7{HTTP/1.1}{127.0.0.1:57760} [junit4] 2> 182799 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.e.j.s.Server Started @186349ms [junit4] 2> 182799 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=38494, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores} [junit4] 2> 182800 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 182800 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001' [junit4] 2> 182800 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 182800 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 182801 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 182801 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 182802 INFO (zkCallback-221-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c78c8e4 name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 182803 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 182803 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 182805 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 182805 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/solr.xml [junit4] 2> 182810 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores [junit4] 2> 182810 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer New CoreContainer 1685130261 [junit4] 2> 182811 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001] [junit4] 2> 182811 WARN (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/lib [junit4] 2> 182811 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] 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> 182812 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39851/solr [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 182813 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 182815 INFO (zkCallback-224-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@794731b3 name:ZooKeeperConnection Watcher:127.0.0.1:39851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 182816 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 182816 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 182817 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 182819 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c446bfb name:ZooKeeperConnection Watcher:127.0.0.1:39851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 182819 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 182824 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 183830 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38494_ [junit4] 2> 183831 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:38494_ [junit4] 2> 183832 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] 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> 183832 INFO (zkCallback-220-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> 183833 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ ] 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> 183834 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 183835 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95189176525193224-127.0.0.1:38494_-n_0000000001 [junit4] 2> 183835 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95189176525193220-127.0.0.1:41694_-n_0000000000 to know if I could be the leader [junit4] 2> 183845 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 183845 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 183846 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores [junit4] 2> 183847 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] 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> 183847 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1 [junit4] 2> 183847 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 183854 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 183854 INFO (TEST-HttpPartitionTest.test-seed#[A0B1DC001F88A06F]) [n:127.0.0.1:38494_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 183854 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 183854 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 183856 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:38494";, [junit4] 2> "node_name":"127.0.0.1:38494_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 183857 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] 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:38494";, [junit4] 2> "node_name":"127.0.0.1:38494_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 183857 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 183857 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 183857 INFO (OverseerStateUpdate-95189176525193220-127.0.0.1:41694_-n_0000000000) [n:127.0.0.1:41694_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 183960 INFO (zkCallback-218-thread-1-processing-n:127.0.0.1:41694_) [n:127.0.0.1:41694_ ] 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> 183960 INFO (zkCallback-225-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ ] 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> 183960 INFO (zkCallback-220-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> 184858 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 184858 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 184859 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 184859 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/collection1] [junit4] 2> 184859 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/collection1] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 184859 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1' [junit4] 2> 184859 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 184860 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 184861 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 184867 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 184871 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 184897 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 184903 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 184990 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 185000 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 185001 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 185012 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 185014 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 185020 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 185022 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 185024 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 185024 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 185024 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 185024 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/] [junit4] 2> 185024 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2cc9a864 [junit4] 2> 185025 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data [junit4] 2> 185025 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/index/ [junit4] 2> 185025 WARN (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 185025 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/index [junit4] 2> 185026 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7250565167799969] [junit4] 2> 185026 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@59439f84 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a0f4c2e),segFN=segments_1,generation=1} [junit4] 2> 185026 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 185027 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/ [junit4] 2> 185027 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.HttpPartitionTest_A0B1DC001F88A06F-001/shard-1-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 185032 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 185032 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 185032 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 185032 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 185032 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 185033 INFO (coreLoadExecutor-581-thread-1-processing-n:127.0.0.1:38494_) [n:127.0.0.1:38494_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorC [...truncated too long message...] ateLog.java:316) [junit4] 2> at org.apache.solr.update.UpdateLog.add(UpdateLog.java:428) [junit4] 2> at org.apache.solr.update.UpdateLog.add(UpdateLog.java:415) [junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.doNormalUpdate(DirectUpdateHandler2.java:303) [junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:215) [junit4] 2> at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:170) [junit4] 2> at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69) [junit4] 2> at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:49) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:932) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1087) [junit4] 2> at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:710) [junit4] 2> at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:104) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:98) [junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:179) [junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:135) [junit4] 2> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:260) [junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:121) [junit4] 2> at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:225) [junit4] 2> at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:145) [junit4] 2> at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:186) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:108) [junit4] 2> at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:55) [junit4] 2> at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:95) [junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:70) [junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:156) [junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2062) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:650) [junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:457) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:226) [junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:184) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:110) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) [junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) [junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) [junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) [junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) [junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:499) [junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) [junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) [junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) [junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> Jan 11, 2016 12:55:55 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=en_GB, timezone=Europe/London [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=2,free=208048904,total=529530880 [junit4] 2> NOTE: All tests run in this JVM: [OutputWriterTest, TestSolr4Spatial, TestClusterStateMutator, TestConfig, CoreAdminCreateDiscoverTest, PeerSyncTest, DistributedSpellCheckComponentTest, TestRemoteStreaming, LeaderElectionIntegrationTest, VMParamsZkACLAndCredentialsProvidersTest, InfoHandlerTest, TestMergePolicyConfig, PreAnalyzedFieldTest, TestReplicaProperties, TestInitQParser, TestManagedSchema, TestIBSimilarityFactory, TestHdfsUpdateLog, TestExclusionRuleCollectionAccess, TestFaceting, SpatialRPTFieldTypeTest, TestFastOutputStream, AnalysisAfterCoreReloadTest, TestNamedUpdateProcessors, TestPKIAuthenticationPlugin, TestPseudoReturnFields, BlockJoinFacetRandomTest, BasicAuthIntegrationTest, RAMDirectoryFactoryTest, TestBadConfig, TestRestManager, TestManagedSchemaFieldResource, TestShortCircuitedRequests, TestAtomicUpdateErrorCases, CurrencyFieldOpenExchangeTest, TestUseDocValuesAsStored2, TestMacros, JavabinLoaderTest, ExternalFileFieldSortTest, LeaderInitiatedRecoveryOnCommitTest, ResourceLoaderTest, TestReplicationHandler, OverseerTaskQueueTest, OverseerCollectionConfigSetProcessorTest, TestLRUCache, ResponseHeaderTest, ReturnFieldsTest, TestFoldingMultitermQuery, TestDocSet, TestConfigSetsAPI, QueryElevationComponentTest, BasicDistributedZkTest, TestBinaryField, TestSolrQueryParserResource, TestBlendedInfixSuggestions, TestCrossCoreJoin, TestFunctionQuery, TestSimpleQParserPlugin, DistributedFacetPivotLongTailTest, DistributedFacetPivotWhiteBoxTest, SolrCoreTest, AtomicUpdatesTest, TestRecoveryHdfs, TestReplicationHandlerBackup, QueryEqualityTest, TestCollapseQParserPlugin, RequiredFieldsTest, DateFieldTest, CdcrReplicationDistributedZkTest, URLClassifyProcessorTest, CollectionTooManyReplicasTest, CursorPagingTest, HdfsChaosMonkeyNothingIsSafeTest] [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsChaosMonkeyNothingIsSafeTest -Dtests.seed=A0B1DC001F88A06F -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=en_GB -Dtests.timezone=Europe/London -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] ERROR 0.00s J0 | HdfsChaosMonkeyNothingIsSafeTest (suite) <<< [junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 2 object(s) that were not released!!! [HdfsTransactionLog, HdfsTransactionLog] [junit4] > at __randomizedtesting.SeedInfo.seed([A0B1DC001F88A06F]:0) [junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:229) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] Completed [278/575 (3!)] on J0 in 212.93s, 1 test, 1 failure <<< FAILURES! [...truncated 984 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1424: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: There were test failures: 575 suites (8 ignored), 2290 tests, 2 suite-level errors, 1 error, 33 ignored (18 assumptions) [seed: A0B1DC001F88A06F] Total time: 207 minutes 35 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]
