Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/840/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=18519, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=18519, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:51725/se/nh: collection already exists: awholynewstresscollection_collection0_0 at __randomizedtesting.SeedInfo.seed([F7260FB173B27178]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:575) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1099) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888) FAILED: org.apache.solr.cloud.RollingRestartTest.test Error Message: Unable to restart (#6): CloudJettyRunner [url=http://127.0.0.1:49972/collection1] Stack Trace: java.lang.AssertionError: Unable to restart (#6): CloudJettyRunner [url=http://127.0.0.1:49972/collection1] at __randomizedtesting.SeedInfo.seed([F7260FB173B27178:7F72306BDD4E1C80]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:104) at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) 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:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR Error Message: Captured an uncaught exception in thread: Thread[id=15832, name=coreZkRegister-5980-thread-2, state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=15832, name=coreZkRegister-5980-thread-2, state=RUNNABLE, group=TGRP-LeaderInitiatedRecoveryOnShardRestartTest] Caused by: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([F7260FB173B27178]:0) at org.apache.solr.cloud.ZkController.updateLeaderInitiatedRecoveryState(ZkController.java:2126) at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:433) at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:197) at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:157) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:346) at org.apache.solr.cloud.ZkController.joinElection(ZkController.java:1113) at org.apache.solr.cloud.ZkController.register(ZkController.java:926) at org.apache.solr.cloud.ZkController.register(ZkController.java:881) at org.apache.solr.core.ZkContainer$2.run(ZkContainer.java:183) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:231) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 10375 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/init-core-data-001 [junit4] 2> 1324402 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F7260FB173B27178]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 1324402 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[F7260FB173B27178]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /se/nh [junit4] 2> 1324406 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1324406 INFO (Thread-12325) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1324406 INFO (Thread-12325) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1324506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkTestServer start zk server on port:59505 [junit4] 2> 1324506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1324507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1324509 INFO (zkCallback-1264-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6abf9406 name:ZooKeeperConnection Watcher:127.0.0.1:59505 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1324510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1324510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1324510 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1324513 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1324513 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1324514 INFO (zkCallback-1265-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4653b39d name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1324514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1324515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1324515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1324517 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1324518 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1324519 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1324520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1324520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1324523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1324523 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1324524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1324524 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1324526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1324526 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1324527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1324527 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1324529 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1324529 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1324530 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1324531 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1324532 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1324532 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1324533 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1324534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1324535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1324535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1324537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1324537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1324902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1 [junit4] 2> 1324904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1324905 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6cb2c5c3{/se/nh,null,AVAILABLE} [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7d13f6af{HTTP/1.1}{127.0.0.1:60070} [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.Server Started @1328149ms [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/tempDir-001/control/data, hostContext=/se/nh, hostPort=60070, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores} [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/' [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1324906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1324924 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1324925 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1324926 INFO (zkCallback-1266-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2fbfafb name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1324926 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1324926 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1324927 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1324928 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/solr.xml [junit4] 2> 1324933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores [junit4] 2> 1324933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer New CoreContainer 655098749 [junit4] 2> 1324933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/] [junit4] 2> 1324933 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/lib [junit4] 2> 1324933 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/lib). [junit4] 2> 1324950 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] 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> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59505/solr [junit4] 2> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1324952 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1324953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1324954 INFO (zkCallback-1268-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e20cd94 name:ZooKeeperConnection Watcher:127.0.0.1:59505 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1324954 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1324955 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1324957 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1324958 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@bcae212 name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1324958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1324960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1324962 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1324965 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1324967 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1324969 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1324971 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1324972 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1324973 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1324975 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1324976 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60070_se%2Fnh [junit4] 2> 1324977 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:60070_se%2Fnh [junit4] 2> 1324978 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1324979 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1324980 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1324981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000 [junit4] 2> 1324981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60070_se%2Fnh [junit4] 2> 1324981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1324982 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer Overseer (id=94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) starting [junit4] 2> 1324984 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1324989 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1324990 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1324990 INFO (OverseerCollectionConfigSetProcessor-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1324991 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1325009 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1325009 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1325010 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores [junit4] 2> 1325011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1325011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/ [junit4] 2> 1325011 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1325012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1325012 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1325013 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1325013 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1325015 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1325015 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60070/se/nh";, [junit4] 2> "node_name":"127.0.0.1:60070_se%2Fnh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 1325015 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60070/se/nh";, [junit4] 2> "node_name":"127.0.0.1:60070_se%2Fnh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1325016 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1325017 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1325018 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] 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> 1326015 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1326015 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1326016 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1326016 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1326017 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1326017 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/' [junit4] 2> 1326017 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1326017 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1326031 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1326035 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1326039 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1326047 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1326048 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1326053 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1326172 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1326177 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1326179 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1326187 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1326189 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1326195 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1326196 WARN (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1326196 WARN (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1326196 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1326197 WARN (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1326197 WARN (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1326198 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1326198 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1326198 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1326198 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1326198 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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@3cb7d63c [junit4] 2> 1326199 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data [junit4] 2> 1326200 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data/index/ [junit4] 2> 1326200 WARN (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1326200 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data/index [junit4] 2> 1326200 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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=23, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 1326201 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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@2302f610 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ab9004d),segFN=segments_1,generation=1} [junit4] 2> 1326201 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1326201 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data/ [junit4] 2> 1326202 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1326205 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326206 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326208 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1326209 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1326210 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1326211 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1326214 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 1326214 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326215 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326215 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326215 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1326215 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1326216 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.361328125, noCFSRatio=0.487429656309311] [junit4] 2> 1326216 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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@2302f610 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5ab9004d),segFN=segments_1,generation=1} [junit4] 2> 1326216 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1326217 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@63335722[collection1] main [junit4] 2> 1326217 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326218 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326218 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1326219 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1326220 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326220 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326220 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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> 1326220 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh 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 1516726867984384000 [junit4] 2> 1326222 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1516726867984384000 [junit4] 2> 1326222 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1326223 INFO (searcherExecutor-4821-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63335722[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1326223 INFO (coreLoadExecutor-4820-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1326223 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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:60070/se/nh collection:control_collection shard:shard1 [junit4] 2> 1326224 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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> 1326227 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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/94795429130207236-core_node1-n_0000000000 [junit4] 2> 1326227 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60070/se/nh/collection1/ [junit4] 2> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1326229 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] 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> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:60070/se/nh/collection1/ has no replicas [junit4] 2> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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:60070/se/nh/collection1/ shard1 [junit4] 2> 1326229 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1326232 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1326234 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] 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:60070/se/nh";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1326337 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] 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> 1326385 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:60070/se/nh/collection1/ and leader is http://127.0.0.1:60070/se/nh/collection1/ [junit4] 2> 1326385 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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:60070/se/nh [junit4] 2> 1326385 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1326385 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1326385 INFO (coreZkRegister-4814-thread-1-processing-n:127.0.0.1:60070_se%2Fnh x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:60070_se%2Fnh 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> 1326387 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60070/se/nh";, [junit4] 2> "node_name":"127.0.0.1:60070_se%2Fnh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 1326387 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:60070/se/nh";, [junit4] 2> "node_name":"127.0.0.1:60070_se%2Fnh", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1326490 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] 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> 1326514 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1326515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1326516 INFO (zkCallback-1271-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@57efd54f name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326516 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1326516 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1326516 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1326518 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1326844 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores/collection1 [junit4] 2> 1326845 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001 [junit4] 2> 1326846 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a7684ca{/se/nh,null,AVAILABLE} [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.ServerConnector Started ServerConnector@65f3e651{HTTP/1.1}{127.0.0.1:51725} [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.e.j.s.Server Started @1330091ms [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/se/nh, hostPort=51725, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores} [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1326848 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/' [junit4] 2> 1326849 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1326849 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1326865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1326865 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1326867 INFO (zkCallback-1272-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a12381f name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326867 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1326867 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1326868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1326868 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/solr.xml [junit4] 2> 1326873 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores [junit4] 2> 1326873 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer New CoreContainer 1900683545 [junit4] 2> 1326873 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/] [junit4] 2> 1326873 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/lib [junit4] 2> 1326873 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/lib). [junit4] 2> 1326881 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] 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> 1326882 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1326903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1326903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1326903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59505/solr [junit4] 2> 1326903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1326903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1326904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1326905 INFO (zkCallback-1274-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@10d5485e name:ZooKeeperConnection Watcher:127.0.0.1:59505 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326905 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1326905 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1326906 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1326907 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@30b2be61 name:ZooKeeperConnection Watcher:127.0.0.1:59505/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1326907 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1326912 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1327917 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51725_se%2Fnh [junit4] 2> 1327917 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51725_se%2Fnh [junit4] 2> 1327918 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1327918 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1327918 INFO (zkCallback-1271-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 1327921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1327921 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94795429130207240-127.0.0.1:51725_se%2Fnh-n_0000000001 [junit4] 2> 1327922 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000 to know if I could be the leader [junit4] 2> 1327935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1327935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1327936 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores [junit4] 2> 1327937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1327937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores/collection1/ [junit4] 2> 1327937 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1327938 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 1327938 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[F7260FB173B27178]) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1327939 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1327939 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1327940 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51725/se/nh";, [junit4] 2> "node_name":"127.0.0.1:51725_se%2Fnh", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1327941 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1327941 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51725/se/nh";, [junit4] 2> "node_name":"127.0.0.1:51725_se%2Fnh", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 1327941 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1327941 INFO (OverseerStateUpdate-94795429130207236-127.0.0.1:60070_se%2Fnh-n_0000000000) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1328043 INFO (zkCallback-1269-thread-1-processing-n:127.0.0.1:60070_se%2Fnh) [n:127.0.0.1:60070_se%2Fnh ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1328043 INFO (zkCallback-1275-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1328043 INFO (zkCallback-1271-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 1328941 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1328942 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1328942 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1328942 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 1328943 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1328943 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_F7260FB173B27178-001/shard-1-001/cores/collection1/' [junit4] 2> 1328943 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1328943 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1328957 INFO (coreLoadExecutor-4831-thread-1-processing-n:127.0.0.1:51725_se%2Fnh) [n:127.0.0.1:51725_se%2Fnh c:collection1 [...truncated too long message...] seer_elect/election/94795528501461048-127.0.0.1:55566_-n_0000000023 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461018-127.0.0.1:47741_-n_0000000007 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461141-core_node15-n_0000000017 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461336-127.0.0.1:55566_-n_0000000155 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94795528501461174-core_node10-n_0000000038 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461165-core_node5-n_0000000019 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461006-127.0.0.1:49643_-n_0000000020 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461096-127.0.0.1:55566_-n_0000000045 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461213-core_node5-n_0000000024 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461189-core_node15-n_0000000022 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461024-127.0.0.1:39481_-n_0000000009 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461006-127.0.0.1:49643_-n_0000000163 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461261-core_node5-n_0000000029 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461333-core_node15-n_0000000037 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461309-core_node5-n_0000000034 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94795528501461342-core_node8-n_0000000075 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461009-127.0.0.1:40189_-n_0000000021 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94795528501461126-core_node10-n_0000000027 [junit4] 2> 2 /solr/overseer_elect/election/94795528501461036-127.0.0.1:49741_-n_0000000013 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461117-core_node5-n_0000000014 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94795528501461078-core_node10-n_0000000016 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94795528501461042-core_node15-n_0000000007 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 122 /solr/collections [junit4] 2> 58 /solr/overseer/collection-queue-work [junit4] 2> 57 /solr/overseer/queue [junit4] 2> 20 /solr/overseer/queue-work [junit4] 2> 18 /solr/live_nodes [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RollingRestartTest -Dtests.method=test -Dtests.seed=F7260FB173B27178 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=ko_KR -Dtests.timezone=Europe/Zagreb -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 819s J2 | RollingRestartTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: Unable to restart (#6): CloudJettyRunner [url=http://127.0.0.1:49972/collection1] [junit4] > at __randomizedtesting.SeedInfo.seed([F7260FB173B27178:7F72306BDD4E1C80]:0) [junit4] > at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:104) [junit4] > at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:53) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 3659831 INFO (SUITE-RollingRestartTest-seed#[F7260FB173B27178]-worker) [n:127.0.0.1:60226_ c:collection1 s:shard1 r:core_node16 x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.RollingRestartTest_F7260FB173B27178-001 [junit4] 2> Nov 02, 2015 12:03:12 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, sim=ClassicSimilarity, locale=ko_KR, timezone=Europe/Zagreb [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=1,free=91458864,total=518520832 [junit4] 2> NOTE: All tests run in this JVM: [DistribCursorPagingTest, OverriddenZkACLAndCredentialsProvidersTest, AnalyticsMergeStrategyTest, ShowFileRequestHandlerTest, TestExactSharedStatsCache, QueryParsingTest, AddBlockUpdateTest, CursorMarkTest, TestOverriddenPrefixQueryForCustomFieldType, TestDistributedStatsComponentCardinality, XmlUpdateRequestHandlerTest, TestManagedStopFilterFactory, TestPerFieldSimilarity, ResponseHeaderTest, UnloadDistributedZkTest, CurrencyFieldXmlFileTest, DistributedQueryComponentCustomSortTest, DocValuesMultiTest, TestLeaderElectionZkExpiry, AsyncMigrateRouteKeyTest, TestConfigSetsAPI, TestDefaultSimilarityFactory, TermVectorComponentDistributedTest, HdfsDirectoryFactoryTest, LeaderElectionTest, DirectUpdateHandlerTest, SuggesterWFSTTest, TestSha256AuthenticationProvider, ConcurrentDeleteAndCreateCollectionTest, UpdateParamsTest, ClusterStateTest, TestReversedWildcardFilterFactory, RecoveryZkTest, TestFieldTypeResource, CloudMLTQParserTest, TestSmileRequest, TestWriterPerf, TestXIncludeConfig, DistributedDebugComponentTest, OverseerTest, TestPostingsSolrHighlighter, ZkNodePropsTest, TestSearchPerf, HdfsBasicDistributedZkTest, TestMinMaxOnMultiValuedField, SolrCoreCheckLockOnStartupTest, TestSort, BasicDistributedZk2Test, TestCSVResponseWriter, BlockCacheTest, LukeRequestHandlerTest, AutoCommitTest, TestStressReorder, PrimitiveFieldTypeTest, SearchHandlerTest, TestManagedResourceStorage, TestAuthenticationFramework, TestIndexSearcher, FullSolrCloudDistribCmdsTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestElisionMultitermQuery, SecurityConfHandlerTest, DeleteShardTest, HLLSerializationTest, TestSolrIndexConfig, TestGroupingSearch, TestSolrCLIRunExample, BinaryUpdateRequestHandlerTest, OverseerCollectionConfigSetProcessorTest, TestFieldResource, TestScoreJoinQPNoScore, BasicAuthIntegrationTest, TestShortCircuitedRequests, TestCoreDiscovery, HighlighterConfigTest, RegexBoostProcessorTest, BigEndianAscendingWordDeserializerTest, ZkStateReaderTest, FieldAnalysisRequestHandlerTest, CoreAdminHandlerTest, CdcrUpdateLogTest, TestTrieFacet, TestQueryWrapperFilter, QueryResultKeyTest, BadComponentTest, TestManagedSchemaDynamicFieldResource, TestReload, AnalyticsQueryTest, BasicZkTest, TestZkChroot, TestRandomDVFaceting, TestDistributedSearch, TestCoreContainer, SolrCmdDistributorTest, TestFiltering, SolrRequestParserTest, SuggesterTest, PolyFieldTest, FieldMutatingUpdateProcessorTest, QueryEqualityTest, TestRemoteStreaming, SolrInfoMBeanTest, DebugComponentTest, TestQueryUtils, TermsComponentTest, PathHierarchyTokenizerFactoryTest, TermVectorComponentTest, TestSolrQueryParser, TestJmxIntegration, JsonLoaderTest, TestBinaryResponseWriter, AlternateDirectoryTest, SolrIndexConfigTest, TestDocSet, MinimalSchemaTest, TestConfig, TestFuzzyAnalyzedSuggestions, TestPhraseSuggestions, TestSweetSpotSimilarityFactory, TestFastWriter, TestFastOutputStream, ScriptEngineTest, TestSystemIdResolver, TestSuggestSpellingConverter, DateFieldTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest, TestSolrJ, TestLRUCache, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, FileUtilsTest, TestRTGBase, CursorPagingTest, BaseCdcrDistributedZkTest, CdcrRequestHandlerTest, CollectionStateFormat2Test, DeleteInactiveReplicaTest, DistributedVersionInfoTest, MigrateRouteKeyTest, MultiThreadedOCPTest, OverseerStatusTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest, RollingRestartTest] [junit4] Completed [451/553] on J2 in 819.59s, 1 test, 1 failure <<< FAILURES! [...truncated 316 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 553 suites (8 ignored), 2176 tests, 2 errors, 1 failure, 31 ignored (18 assumptions) [seed: F7260FB173B27178] Total time: 198 minutes 18 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
