Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/1/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=13997, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=13997, 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:45031: collection already exists: awholynewstresscollection_collection5_2 at __randomizedtesting.SeedInfo.seed([2C56F40C5946B548]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1572) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:887) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=30849, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=30849, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:41552/nobu/s: Could not find collection : awholynewstresscollection_collection5_0 at __randomizedtesting.SeedInfo.seed([2C56F40C5946B548]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1098) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:869) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:805) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) FAILED: org.apache.solr.core.HdfsDirectoryFactoryTest.testInitArgsOrSysPropConfig Error Message: The max direct memory is likely too low. Either increase it (by adding -XX:MaxDirectMemorySize=<size>g -XX:+UseLargePages to your containers startup args) or disable direct allocation using solr.hdfs.blockcache.direct.memory.allocation=false in solrconfig.xml. If you are putting the block cache on the heap, your java heap size might not be large enough. Failed allocating ~134.217728 MB. Stack Trace: java.lang.RuntimeException: The max direct memory is likely too low. Either increase it (by adding -XX:MaxDirectMemorySize=<size>g -XX:+UseLargePages to your containers startup args) or disable direct allocation using solr.hdfs.blockcache.direct.memory.allocation=false in solrconfig.xml. If you are putting the block cache on the heap, your java heap size might not be large enough. Failed allocating ~134.217728 MB. at __randomizedtesting.SeedInfo.seed([2C56F40C5946B548:DBF93D27A4CF5F63]:0) at org.apache.solr.core.HdfsDirectoryFactory.createBlockCache(HdfsDirectoryFactory.java:293) at org.apache.solr.core.HdfsDirectoryFactory.getBlockDirectoryCache(HdfsDirectoryFactory.java:269) at org.apache.solr.core.HdfsDirectoryFactory.create(HdfsDirectoryFactory.java:211) at org.apache.solr.core.HdfsDirectoryFactoryTest.testInitArgsOrSysPropConfig(HdfsDirectoryFactoryTest.java:114) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) 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:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) 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:365) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.OutOfMemoryError: Direct buffer memory at java.nio.Bits.reserveMemory(Bits.java:658) at java.nio.DirectByteBuffer.<init>(DirectByteBuffer.java:123) at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:306) at org.apache.solr.store.blockcache.BlockCache.<init>(BlockCache.java:64) at org.apache.solr.core.HdfsDirectoryFactory.createBlockCache(HdfsDirectoryFactory.java:291) ... 42 more Build Log: [...truncated 21737 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/init-core-data-001 [junit4] 2> 1506407 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[2C56F40C5946B548]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1506407 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[2C56F40C5946B548]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1506413 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1506413 INFO (Thread-7823) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1506413 INFO (Thread-7823) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1506513 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkTestServer start zk server on port:55767 [junit4] 2> 1506514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1506515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1506519 INFO (zkCallback-1051-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@25499619 name:ZooKeeperConnection Watcher:127.0.0.1:55767 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1506519 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1506520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1506520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1506524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1506525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1506527 INFO (zkCallback-1052-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@776d4c74 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1506527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1506528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1506528 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1506531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1506534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1506536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1506539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1506539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1506543 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1506544 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1506547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1506547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1506550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1506551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1506553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1506553 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1506556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1506556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1506559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1506559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1506562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1506563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1506566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1506566 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1506569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1506569 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1506572 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1506573 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1506688 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1 [junit4] 2> 1506690 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1506692 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ea65670{/,null,AVAILABLE} [junit4] 2> 1506696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.ServerConnector Started ServerConnector@3c7582a8{HTTP/1.1}{127.0.0.1:48879} [junit4] 2> 1506697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.Server Started @1509931ms [junit4] 2> 1506697 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/tempDir-001/control/data, hostPort=48879, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores} [junit4] 2> 1506698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 1506698 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/' [junit4] 2> 1506718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1506719 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1506721 INFO (zkCallback-1053-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ae3fab2 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1506722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1506722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1506724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1506724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/solr.xml [junit4] 2> 1506743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores [junit4] 2> 1506743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer New CoreContainer 1833085690 [junit4] 2> 1506743 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/] [junit4] 2> 1506744 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/lib [junit4] 2> 1506744 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/lib). [junit4] 2> 1506754 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] 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> 1506756 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1506757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1506757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1506757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55767/solr [junit4] 2> 1506757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1506757 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1506758 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1506760 INFO (zkCallback-1055-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e02df2c name:ZooKeeperConnection Watcher:127.0.0.1:55767 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1506761 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1506762 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1506764 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1506765 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@c287cc4 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1506766 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1506767 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1506773 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1506776 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1506779 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1506782 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1506785 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1506787 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1506789 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1506790 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1506792 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48879_ [junit4] 2> 1506792 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:48879_ [junit4] 2> 1506794 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1506796 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1506798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1506798 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95139142074368004-127.0.0.1:48879_-n_0000000000 [junit4] 2> 1506800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:48879_ [junit4] 2> 1506800 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1506802 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer Overseer (id=95139142074368004-127.0.0.1:48879_-n_0000000000) starting [junit4] 2> 1506805 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1506813 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1506814 INFO (OverseerCollectionProcessor-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 1506814 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1506815 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1506833 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1506833 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1506835 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores [junit4] 2> 1506835 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/, shard=, transient=false, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1, name=collection1, loadOnStartup=true, configSetProperties=configsetprops.json, coreNodeName=, dataDir=data/, collection=control_collection, schema=schema.xml, config=solrconfig.xml} [junit4] 2> 1506836 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/ [junit4] 2> 1506836 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1506837 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1506837 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1506837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2 [junit4] 2> 1506837 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:48879_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1506838 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1506838 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1506839 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:48879_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:48879";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 1506840 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:48879_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:48879";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "roles":null} [junit4] 2> 1506840 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1506840 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1506842 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] 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> 1506843 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 1507839 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1507839 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1507840 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1507840 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1507841 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1507841 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/' [junit4] 2> 1507864 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1507872 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1507887 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2 [junit4] 2> 1507919 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1507920 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1507928 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1508205 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1508214 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1508216 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1508230 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1508234 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1508237 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1508239 WARN (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1508239 WARN (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1508239 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1508244 WARN (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1508244 WARN (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1508245 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2 [junit4] 2> 1508245 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1508246 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1508246 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1508246 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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@629af4bc [junit4] 2> 1508247 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data [junit4] 2> 1508247 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data/index/ [junit4] 2> 1508247 WARN (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1508248 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data/index [junit4] 2> 1508248 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=46, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.14537497714929587] [junit4] 2> 1508249 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@167a9f73 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7a428c),segFN=segments_1,generation=1} [junit4] 2> 1508249 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1508249 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:48879_ 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data/ [junit4] 2> 1508250 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1508255 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1508255 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1508255 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1508255 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1508256 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1508256 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508256 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508256 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508257 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508260 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1508262 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1508263 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1508265 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1508269 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/luke,/admin/mbeans,/admin/system,/update,standard,/config,/update/csv,/schema,/admin/logging,/get,/update/json,/admin/file,/admin/threads,/update/json/docs,/admin/properties,/admin/plugins,/admin/ping,/admin/segments,/replication [junit4] 2> 1508271 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508271 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508272 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508272 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1508273 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1508273 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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=30, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=0.5712890625, floorSegmentMB=0.55859375, forceMergeDeletesPctAllowed=18.160872898579687, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.11279475075256214 [junit4] 2> 1508324 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@167a9f73 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4c7a428c),segFN=segments_1,generation=1} [junit4] 2> 1508324 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1508324 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3b9505d4[collection1] main [junit4] 2> 1508325 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508326 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508326 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508327 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1508327 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1508327 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508328 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508328 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508328 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1508328 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1508329 INFO (searcherExecutor-3547-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3b9505d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1508330 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508330 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508330 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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> 1508330 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ 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 1522226275197911040 [junit4] 2> 1508332 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1522226275197911040 [junit4] 2> 1508333 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1508333 INFO (coreLoadExecutor-3546-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1508334 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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:48879 collection:control_collection shard:shard1 [junit4] 2> 1508335 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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> 1508339 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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/95139142074368004-core_node1-n_0000000000 [junit4] 2> 1508339 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1508340 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1508340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1508341 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1508341 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:48879/collection1/ [junit4] 2> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:48879/collection1/ has no replicas [junit4] 2> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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:48879/collection1/ shard1 [junit4] 2> 1508342 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1508343 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] 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> 1508344 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] 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> 1508345 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d803145 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1508345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1508345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1508345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1508347 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 1508348 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1508348 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1508349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1508350 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] 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:48879";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1508352 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1508353 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] 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> 1508356 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1508356 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1508358 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 1508359 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 1508364 INFO (zkCallback-1059-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1ef88385 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1508364 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1508364 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1508366 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1508367 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1508367 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1508368 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x152008baf940006, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1508400 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:48879/collection1/ and leader is http://127.0.0.1:48879/collection1/ [junit4] 2> 1508400 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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:48879 [junit4] 2> 1508400 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1508401 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1508401 INFO (coreZkRegister-3540-thread-1-processing-r:core_node1 n:127.0.0.1:48879_ x:collection1 c:control_collection s:shard1) [n:127.0.0.1:48879_ 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> 1508402 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1508403 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:48879_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:48879";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null} current state version: 3 [junit4] 2> 1508404 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1:48879_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:48879";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null} [junit4] 2> 1508405 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1508406 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1508406 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] 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> 1508408 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1508408 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1508478 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores/collection1 [junit4] 2> 1508480 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001 [junit4] 2> 1508481 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1508483 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a310c3d{/,null,AVAILABLE} [junit4] 2> 1508483 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.ServerConnector Started ServerConnector@9b2a51f{HTTP/1.1}{127.0.0.1:57761} [junit4] 2> 1508483 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.e.j.s.Server Started @1511717ms [junit4] 2> 1508483 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=57761, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores, hostContext=/} [junit4] 2> 1508484 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 1508484 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/' [junit4] 2> 1508503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1508504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1508506 INFO (zkCallback-1060-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e1a0ed6 name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1508506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1508506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1508507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1508508 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/solr.xml [junit4] 2> 1508511 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1508512 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] 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> 1508513 INFO (zkCallback-1058-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 1508514 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 1508524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores [junit4] 2> 1508525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer New CoreContainer 975162381 [junit4] 2> 1508525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/] [junit4] 2> 1508525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/lib [junit4] 2> 1508525 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/lib). [junit4] 2> 1508534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] 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> 1508536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1508537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1508537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1508537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55767/solr [junit4] 2> 1508537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1508537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1508538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1508539 INFO (zkCallback-1062-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@50aadc40 name:ZooKeeperConnection Watcher:127.0.0.1:55767 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1508541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1508541 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1508543 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1508545 INFO (zkCallback-1063-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f0b221e name:ZooKeeperConnection Watcher:127.0.0.1:55767/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1508545 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1508550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1509557 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57761_ [junit4] 2> 1509558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:57761_ [junit4] 2> 1509562 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1509563 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95139142074368009-127.0.0.1:57761_-n_0000000001 [junit4] 2> 1509564 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95139142074368004-127.0.0.1:48879_-n_0000000000 to know if I could be the leader [junit4] 2> 1509589 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1509590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1509590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores [junit4] 2> 1509592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.SolrCore Created CoreDescriptor: {config=solrconfig.xml, dataDir=data/, name=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores/collection1/, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores/collection1, configSetProperties=configsetprops.json, loadOnStartup=true, transient=false, collection=collection1, coreNodeName=, shard=, schema=schema.xml} [junit4] 2> 1509592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_2C56F40C5946B548-001/shard-1-001/cores/collection1/ [junit4] 2> 1509592 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1509593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2 [junit4] 2> 1509593 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[2C56F40C5946B548]) [n:127.0.0.1:57761_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1509594 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1509594 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1509596 INFO (zkCallback-1056-thread-1-processing-n:127.0.0.1:48879_) [n:127.0.0.1:48879_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1509608 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1 [junit4] 2> 1509608 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 1509609 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:57761_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:57761";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null} current state version: 5 [junit4] 2> 1509609 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1509610 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 0 [junit4] 2> 1509610 INFO (coreLoadExecutor-3557-thread-1-processing-n:127.0.0.1:57761_) [n:127.0.0.1:57761_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1509610 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "node_name":"127.0.0.1:57761_", [junit4] 2> "core":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:57761";, [junit4] 2> "operation":"state", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null} [junit4] 2> 1509611 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_0000000000) [n:127.0.0.1:48879_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1509611 INFO (OverseerStateUpdate-95139142074368004-127.0.0.1:48879_-n_00000000 [...truncated too long message...] com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 4266425 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[2C56F40C5946B548]-worker) [n:127.0.0.1:44173_nobu%2Fs c:addReplicaColl s:shard1 r:core_node1 x:addReplicaColl_shard1_replica2] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Jan 02, 2016 5:01:01 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=PostingsFormat(name=LuceneVarGapFixedInterval), a_t=FSTOrd50, id=PostingsFormat(name=MockRandom), a_i1=FSTOrd50, text=PostingsFormat(name=Memory doPackFST= false), range_facet_l_dv=PostingsFormat(name=MockRandom), multiDefault=FSTOrd50, _version_=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l=FSTOrd50, range_facet_i_dv=FSTOrd50, timestamp=FSTOrd50, intDefault=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=no, timezone=Etc/GMT-8 [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=245665584,total=524288000 [junit4] 2> NOTE: All tests run in this JVM: [TestCustomSort, PrimUtilsTest, TestStressReorder, StatsComponentTest, SolrInfoMBeanTest, ConnectionManagerTest, NotRequiredUniqueKeyTest, TestRawResponseWriter, HdfsRecoveryZkTest, DeleteShardTest, CleanupOldIndexTest, DistribJoinFromCollectionTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SampleTest, TestSchemaSimilarityResource, SolrCoreCheckLockOnStartupTest, StressHdfsTest, TestSurroundQueryParser, TestExactStatsCache, DOMUtilTest, TestPerFieldSimilarity, TestTrie, SimpleFacetsTest, DocExpirationUpdateProcessorFactoryTest, SolrXmlInZkTest, TestZkChroot, HdfsBasicDistributedZk2Test, QueryParsingTest, CircularListTest, MoreLikeThisHandlerTest, TestSearcherReuse, SegmentsInfoRequestHandlerTest, DistributedQueryComponentCustomSortTest, ConcurrentDeleteAndCreateCollectionTest, BinaryUpdateRequestHandlerTest, TestSolrJ, PathHierarchyTokenizerFactoryTest, CoreMergeIndexesAdminHandlerTest, TestBlendedInfixSuggestions, TestDefaultSimilarityFactory, TestSolrQueryParser, TestSchemaManager, CoreAdminRequestStatusTest, SolrCloudExampleTest, XmlUpdateRequestHandlerTest, PluginInfoTest, SOLR749Test, TestManagedResource, TestCSVResponseWriter, TestSha256AuthenticationProvider, TestQueryUtils, NumericFieldsTest, DistributedExpandComponentTest, TestDocBasedVersionConstraints, TestNonDefinedSimilarityFactory, QueryResultKeyTest, TestLuceneMatchVersion, CloudExitableDirectoryReaderTest, TermVectorComponentDistributedTest, HdfsDirectoryTest, FullSolrCloudDistribCmdsTest, HdfsRecoverLeaseTest, DeleteReplicaTest, OverseerTest, DocValuesTest, TestCollationFieldDocValues, RegexBoostProcessorTest, TestSimpleTrackingShardHandler, TestManagedSynonymFilterFactory, TestChildDocTransformer, TestObjectReleaseTracker, TestPKIAuthenticationPlugin, TestCursorMarkWithoutUniqueKey, TestGroupingSearch, TestFastOutputStream, ResponseLogComponentTest, TestManagedSchemaDynamicFieldResource, SolrTestCaseJ4Test, QueryEqualityTest, HLLUtilTest, MultiThreadedOCPTest, DirectUpdateHandlerOptimizeTest, DistributedQueryElevationComponentTest, AutoCommitTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, ClusterStateUpdateTest, LeaderElectionTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestRecovery, ZkControllerTest, TestRealTimeGet, TestReload, TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, SpellCheckComponentTest, SolrCmdDistributorTest, PeerSyncTest, BadIndexSchemaTest, TestSort, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, OverseerCollectionProcessorTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, TestFoldingMultitermQuery, SuggesterTSTTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestUpdate, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, TermsComponentTest, DocumentBuilderTest, TermVectorComponentTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, JSONWriterTest, JsonLoaderTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, SearchHandlerTest, TestBinaryResponseWriter, TestLFUCache, HighlighterConfigTest, TestQuerySenderNoQuery, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, TestDocSet, TestBinaryField, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestPhraseSuggestions, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, TestJmxMonitoredMap, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, TestTolerantSearch, AsyncMigrateRouteKeyTest, CollectionTooManyReplicasTest, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, RecoveryAfterSoftCommitTest, SimpleCollectionCreateDeleteTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCloudPivotFacet, TestCollectionAPI, TestCryptoKeys, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestMiniSolrCloudCluster, TestMiniSolrCloudClusterSSL, TestRebalanceLeaders, TestSolrCloudWithKerberosAlt, TriLevelCompositeIdRoutingTest, HdfsChaosMonkeySafeLeaderTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [500/533] on J2 in 987.85s, 1 test, 1 error <<< FAILURES! [...truncated 118 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 533 suites, 2103 tests, 3 errors, 26 ignored (14 assumptions) Total time: 189 minutes 46 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
