Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/10/
4 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest Error Message: Java heap space Stack Trace: java.lang.OutOfMemoryError: Java heap space REGRESSION: org.apache.solr.cloud.RollingRestartTest.test Error Message: Unable to restart (#0): CloudJettyRunner [url=http://127.0.0.1:41463/collection1] Stack Trace: java.lang.AssertionError: Unable to restart (#0): CloudJettyRunner [url=http://127.0.0.1:41463/collection1] at __randomizedtesting.SeedInfo.seed([19C2D7FD50A4B9B2:9196E827FE58D44A]: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:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at 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:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Java heap space Stack Trace: java.lang.OutOfMemoryError: Java heap space at java.lang.Class.getDeclaredFields0(Native Method) at java.lang.Class.privateGetDeclaredFields(Class.java:2499) at java.lang.Class.privateGetPublicFields(Class.java:2530) at java.lang.Class.getFields(Class.java:1420) at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.determineType(JmxMonitoredMap.java:323) at org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:298) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getAttribute(DefaultMBeanServerInterceptor.java:644) at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttribute(JmxMBeanServer.java:678) at javax.management.AttributeValueExp.getAttribute(AttributeValueExp.java:163) at javax.management.AttributeValueExp.apply(AttributeValueExp.java:105) at javax.management.BinaryRelQueryExp.apply(BinaryRelQueryExp.java:109) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.filterListOfObjectInstances(DefaultMBeanServerInterceptor.java:1626) at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNames(DefaultMBeanServerInterceptor.java:545) at com.sun.jmx.mbeanserver.JmxMBeanServer.queryNames(JmxMBeanServer.java:619) at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:144) at org.apache.solr.core.SolrCore.close(SolrCore.java:1246) at org.apache.solr.core.SolrCores.close(SolrCores.java:124) at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:562) at org.apache.solr.servlet.SolrDispatchFilter.destroy(SolrDispatchFilter.java:172) at org.apache.solr.cloud.ChaosMonkey.stopJettySolrRunner(ChaosMonkey.java:198) at org.apache.solr.cloud.ChaosMonkey.stop(ChaosMonkey.java:552) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.destroyServers(AbstractFullDistribZkTestBase.java:1509) at org.apache.solr.BaseDistributedSearchTestCase.distribTearDown(BaseDistributedSearchTestCase.java:311) at org.apache.solr.cloud.AbstractDistribZkTestBase.distribTearDown(AbstractDistribZkTestBase.java:255) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.distribTearDown(AbstractFullDistribZkTestBase.java:1487) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:940) 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) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=185908, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=185908, name=collection1, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:33639/p_g/e: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([19C2D7FD50A4B9B2]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) Build Log: [...truncated 10755 lines...] [junit4] Suite: org.apache.solr.cloud.RollingRestartTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/init-core-data-001 [junit4] 2> 1297697 INFO (SUITE-RollingRestartTest-seed#[19C2D7FD50A4B9B2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1297697 INFO (SUITE-RollingRestartTest-seed#[19C2D7FD50A4B9B2]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1297703 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1297705 INFO (Thread-2479) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1297705 INFO (Thread-2479) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1297805 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkTestServer start zk server on port:33002 [junit4] 2> 1297805 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1297825 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1297833 INFO (zkCallback-750-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@301210dd name:ZooKeeperConnection Watcher:127.0.0.1:33002 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1297833 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1297834 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1297834 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1297838 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1297846 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1297849 INFO (zkCallback-751-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20ebee3d name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1297850 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1297850 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1297850 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1297853 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1297862 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1297865 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1297870 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1297870 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1297873 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1297874 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1297875 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1297876 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1297877 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1297877 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1297879 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1297880 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1297881 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1297881 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1297883 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1297884 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1297885 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1297886 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1297887 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1297887 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1297890 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1297890 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1297891 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1297892 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1298414 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1 [junit4] 2> 1298429 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1298437 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ca1635e{/,null,AVAILABLE} [junit4] 2> 1298442 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.ServerConnector Started ServerConnector@78c9eeea{HTTP/1.1}{127.0.0.1:55078} [junit4] 2> 1298444 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.Server Started @1301636ms [junit4] 2> 1298444 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores, hostPort=55078, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/tempDir-001/control/data} [junit4] 2> 1298445 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0add57 [junit4] 2> 1298445 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/' [junit4] 2> 1298470 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1298471 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1298492 INFO (zkCallback-752-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3016df66 name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1298494 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1298494 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1298496 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1298496 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/solr.xml [junit4] 2> 1298514 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores [junit4] 2> 1298515 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer New CoreContainer 1851165921 [junit4] 2> 1298515 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/] [junit4] 2> 1298515 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/lib [junit4] 2> 1298517 WARN (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/lib). [junit4] 2> 1298527 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] 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> 1298529 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1298530 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1298530 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1298530 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33002/solr [junit4] 2> 1298530 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1298531 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1298542 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1298548 INFO (zkCallback-754-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2677c5a2 name:ZooKeeperConnection Watcher:127.0.0.1:33002 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1298550 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1298550 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1298564 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1298578 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@557a8454 name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1298578 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1298580 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1298583 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1298585 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1298589 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1298591 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1298594 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1298596 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1298599 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1298601 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1298602 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55078_ [junit4] 2> 1298603 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55078_ [junit4] 2> 1298604 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1298605 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1298608 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1298608 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94372999510491140-127.0.0.1:55078_-n_0000000000 [junit4] 2> 1298610 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55078_ [junit4] 2> 1298611 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1298614 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer Overseer (id=94372999510491140-127.0.0.1:55078_-n_0000000000) starting [junit4] 2> 1298617 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1298626 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1298629 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1298648 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1298649 INFO (OverseerCollectionProcessor-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 1298666 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1298666 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1298667 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores [junit4] 2> 1298669 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.SolrCore Created CoreDescriptor: {instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1, transient=false, configSetProperties=configsetprops.json, loadOnStartup=true, coreNodeName=, shard=, dataDir=data/, config=solrconfig.xml, name=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/, collection=control_collection, schema=schema.xml} [junit4] 2> 1298669 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/ [junit4] 2> 1298670 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1298671 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1298671 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1298677 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1298677 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1298678 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1 [junit4] 2> 1298678 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:55078_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1298680 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:55078_", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:55078";, [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} current state version: 0 [junit4] 2> 1298682 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:55078_", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:55078";, [junit4] 2> "numShards":"1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} [junit4] 2> 1298682 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1298682 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1298685 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] 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> 1298689 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 1299677 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1299678 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1299678 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1299678 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 1299679 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 1299679 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/' [junit4] 2> 1299711 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1299720 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1299746 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0 [junit4] 2> 1299792 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 1299794 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 1299803 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1300333 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1300356 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1300360 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1300381 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1300390 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1300394 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1300405 WARN (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1300406 WARN (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1300406 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1300410 WARN (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 1300410 WARN (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 1300411 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1 [junit4] 2> 1300411 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1300412 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory [junit4] 2> 1300412 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 1300412 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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@262d06f4 [junit4] 2> 1300413 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data [junit4] 2> 1300413 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/index/ [junit4] 2> 1300414 WARN (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1300415 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/index [junit4] 2> 1300415 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=5, maxMergeAtOnceExplicit=4, maxMergedSegmentMB=1.8974609375, floorSegmentMB=1.89453125, forceMergeDeletesPctAllowed=17.737388939352694, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.300668987798983 [junit4] 2> 1300421 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1} [junit4] 2> 1300421 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1300429 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/ [junit4] 2> 1300431 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1300432 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1300432 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1300432 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1300432 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1300433 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300433 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300433 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300433 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300437 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1300439 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1300440 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1300442 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1300446 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/threads,/admin/ping,/admin/system,/admin/luke,/admin/file,/update/json,standard,/admin/plugins,/update,/update/csv,/admin/segments,/replication,/admin/logging,/schema,/update/json/docs,/config,/admin/mbeans,/admin/properties,/get [junit4] 2> 1300448 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300449 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300449 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300450 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 1300450 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 1300450 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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=9, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.18667651172351657] [junit4] 2> 1300451 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1} [junit4] 2> 1300451 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 1300451 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@63e4780[collection1] main [junit4] 2> 1300452 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300452 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300453 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300453 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1300453 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1300453 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300454 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300455 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300455 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1300455 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1300456 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300457 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300458 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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> 1300458 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ 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 1509967995053539328 [junit4] 2> 1300457 INFO (searcherExecutor-2058-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@63e4780[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1300461 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 4 ms to seed version buckets with highest version 1509967995053539328 [junit4] 2> 1300461 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1300464 INFO (coreLoadExecutor-2057-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1300467 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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:55078 collection:control_collection shard:shard1 [junit4] 2> 1300471 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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> 1300476 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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/94372999510491140-core_node1-n_0000000000 [junit4] 2> 1300477 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 1300479 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1300480 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] 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> 1300482 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55078/collection1/ [junit4] 2> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55078/collection1/ has no replicas [junit4] 2> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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:55078/collection1/ shard1 [junit4] 2> 1300483 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1300485 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] 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> 1300487 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 1300489 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1300490 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] 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:55078";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1300595 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] 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> 1300597 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 1300641 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:55078/collection1/ and leader is http://127.0.0.1:55078/collection1/ [junit4] 2> 1300641 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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:55078 [junit4] 2> 1300641 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1300641 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1300641 INFO (coreZkRegister-2051-thread-1-processing-n:127.0.0.1:55078_ r:core_node1 c:control_collection x:collection1 s:shard1) [n:127.0.0.1:55078_ 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> 1300643 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1300644 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:55078_", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:55078";, [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} current state version: 3 [junit4] 2> 1300645 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:55078_", [junit4] 2> "state":"active", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:55078";, [junit4] 2> "numShards":"1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "shard":"shard1", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} [junit4] 2> 1300680 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1300686 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1300687 INFO (zkCallback-757-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@29d8fe6e name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1300687 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1300687 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1300687 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1300689 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1300689 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 1300689 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1300696 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1300706 INFO (zkCallback-758-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5818df49 name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1300707 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1300707 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1300712 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1300714 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 1300714 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 1300715 INFO (zkCallback-757-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1300715 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] 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> 1300718 INFO (zkCallback-757-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1300722 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 1300832 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 1300833 INFO (zkCallback-757-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1) [junit4] 2> 1300833 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] 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> 1300836 INFO (zkCallback-757-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 1300838 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 1301244 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores/collection1 [junit4] 2> 1301245 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001 [junit4] 2> 1301246 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 1301249 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f8b2526{/,null,AVAILABLE} [junit4] 2> 1301251 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.ServerConnector Started ServerConnector@60a31aad{HTTP/1.1}{127.0.0.1:48952} [junit4] 2> 1301251 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.e.j.s.Server Started @1304443ms [junit4] 2> 1301251 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostPort=48952, hostContext=/, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/tempDir-001/jetty1, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores} [junit4] 2> 1301251 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0add57 [junit4] 2> 1301252 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/' [junit4] 2> 1301283 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1301302 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1301304 INFO (zkCallback-759-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34483b51 name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1301304 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1301304 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1301307 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1301307 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/solr.xml [junit4] 2> 1301326 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores [junit4] 2> 1301327 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer New CoreContainer 38185753 [junit4] 2> 1301327 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/] [junit4] 2> 1301328 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/lib [junit4] 2> 1301328 WARN (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/lib). [junit4] 2> 1301339 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] 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> 1301341 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1301341 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1301342 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1301342 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33002/solr [junit4] 2> 1301342 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1301342 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1301358 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1301360 INFO (zkCallback-761-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69f4ed8c name:ZooKeeperConnection Watcher:127.0.0.1:33002 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1301361 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1301362 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1301364 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1301365 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@39382d7f name:ZooKeeperConnection Watcher:127.0.0.1:33002/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1301366 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1301372 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1302385 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48952_ [junit4] 2> 1302385 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:48952_ [junit4] 2> 1302390 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1302390 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94372999510491145-127.0.0.1:48952_-n_0000000001 [junit4] 2> 1302391 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94372999510491140-127.0.0.1:55078_-n_0000000000 to know if I could be the leader [junit4] 2> 1302403 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1302404 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1302406 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores [junit4] 2> 1302407 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.SolrCore Created CoreDescriptor: {instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores/collection1, dataDir=data/, schema=schema.xml, shard=, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores/collection1/, config=solrconfig.xml, configSetProperties=configsetprops.json, coreNodeName=, loadOnStartup=true, name=collection1, collection=collection1, transient=false} [junit4] 2> 1302408 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_19C2D7FD50A4B9B2-001/shard-1-001/cores/collection1/ [junit4] 2> 1302408 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 1302409 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1 [junit4] 2> 1302410 INFO (TEST-RollingRestartTest.test-seed#[19C2D7FD50A4B9B2]) [n:127.0.0.1:48952_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1302414 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1302414 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1302415 INFO (zkCallback-755-thread-1-processing-n:127.0.0.1:55078_) [n:127.0.0.1:55078_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 1302416 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for external collection collection1 [junit4] 2> 1302416 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 1302417 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:48952_", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:48952";, [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} current state version: 5 [junit4] 2> 1302417 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 1302417 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:48952_", [junit4] 2> "state":"down", [junit4] 2> "operation":"state", [junit4] 2> "base_url":"http://127.0.0.1:48952";, [junit4] 2> "numShards":"2", [junit4] 2> "collection":"collection1", [junit4] 2> "shard":null, [junit4] 2> "roles":null, [junit4] 2> "core":"collection1"} [junit4] 2> 1302418 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 1302418 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 1302419 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 0 [junit4] 2> 1302419 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1302421 INFO (OverseerStateUpdate-94372999510491140-127.0.0.1:55078_-n_0000000000) [n:127.0.0.1:55078_ ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 1302422 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 1302423 INFO (zkCallback-762-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ ] o.a.s.c.c.ZkStateReader Updating data for collection1 to ver 1 [junit4] 2> 1303420 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1303420 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 1303421 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1303421 INFO (coreLoadExecutor-2068-thread-1-processing-n:127.0.0.1:48952_) [n:127.0.0.1:48952_ c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 130342 [...truncated too long message...] 112) [junit4] 2> at org.apache.solr.cloud.ZkController$1.command(ZkController.java:296) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:158) [junit4] 2> at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:56) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:132) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> 8700680 WARN (SyncThread:0) [ ] o.a.z.s.p.FileTxnLog fsync-ing the write ahead log in SyncThread:0 took 2471ms which will adversely effect operation latency. See the ZooKeeper troubleshooting guide [junit4] 2> 8700682 ERROR (zkCallback-1623-thread-11-processing-n:127.0.0.1:52157__nci%2Fy-EventThread) [n:127.0.0.1:52157__nci%2Fy c:corewithnocollection2 s:shard1 r:core_node1 x:corewithnocollection2] o.a.s.c.ZkController There was a problem finding the leader in zk:java.lang.InterruptedException: sleep interrupted [junit4] 2> at java.lang.Thread.sleep(Native Method) [junit4] 2> at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1036) [junit4] 2> at org.apache.solr.cloud.ZkController.getLeaderProps(ZkController.java:1009) [junit4] 2> at org.apache.solr.cloud.ZkController.waitForLeaderToSeeDownState(ZkController.java:1600) [junit4] 2> at org.apache.solr.cloud.ZkController.registerAllCoresAsDown(ZkController.java:466) [junit4] 2> at org.apache.solr.cloud.ZkController.access$100(ZkController.java:112) [junit4] 2> at org.apache.solr.cloud.ZkController$1.command(ZkController.java:296) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager$1.update(ConnectionManager.java:158) [junit4] 2> at org.apache.solr.common.cloud.DefaultConnectionStrategy.reconnect(DefaultConnectionStrategy.java:56) [junit4] 2> at org.apache.solr.common.cloud.ConnectionManager.process(ConnectionManager.java:132) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522) [junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498) [junit4] 2> [junit4] 2> 8701370 INFO (zkCallback-1619-thread-23-processing-n:127.0.0.1:43264__nci%2Fy-EventThread) [n:127.0.0.1:43264__nci%2Fy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3d658aa7 name:ZooKeeperConnection Watcher:127.0.0.1:35395/solr got event WatchedEvent state:Expired type:None path:null path:null type:None [junit4] 2> 8701370 INFO (zkCallback-1632-thread-16-processing-n:127.0.0.1:52129__nci%2Fy-EventThread) [n:127.0.0.1:52129__nci%2Fy ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@868be1a name:ZooKeeperConnection Watcher:127.0.0.1:35395/solr got event WatchedEvent state:Expired type:None path:null path:null type:None [junit4] 2> 8701370 INFO (zkCallback-1632-thread-16-processing-n:127.0.0.1:52129__nci%2Fy-EventThread) [n:127.0.0.1:52129__nci%2Fy ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 8702345 INFO (zkCallback-1632-thread-16-processing-n:127.0.0.1:52129__nci%2Fy-EventThread) [n:127.0.0.1:52129__nci%2Fy ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=PostingsFormat(name=LuceneVarGapDocFreqInterval), _version_=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=Lucene50(blocksize=128), a_t=PostingsFormat(name=Memory doPackFST= true), range_facet_l=PostingsFormat(name=Memory doPackFST= true), multiDefault=PostingsFormat(name=Memory doPackFST= true), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= true), a_i1=PostingsFormat(name=Memory doPackFST= true), id=BlockTreeOrds(blocksize=128), timestamp=PostingsFormat(name=Memory doPackFST= true), range_facet_l_dv=BlockTreeOrds(blocksize=128), intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_i_dv=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct), range_facet_l_dv=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=sr_RS_#Latn, timezone=Iceland [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_72 (64-bit)/cpus=4,threads=291,free=43178256,total=467140608 [junit4] 2> NOTE: All tests run in this JVM: [TestObjectReleaseTracker, SyncSliceTest, TermsComponentTest, IndexBasedSpellCheckerTest, ConnectionManagerTest, PrimitiveFieldTypeTest, TestStressLucene, PeerSyncTest, HighlighterTest, TestCryptoKeys, DocValuesMissingTest, TestFieldCollectionResource, TestReqParamsAPI, URLClassifyProcessorTest, TestBlendedInfixSuggestions, CollectionsAPIAsyncDistributedZkTest, EnumFieldTest, TestUpdate, TestBinaryField, HdfsDirectoryFactoryTest, LeaderInitiatedRecoveryOnCommitTest, SaslZkACLProviderTest, SolrCloudExampleTest, ParsingFieldUpdateProcessorsTest, BasicZkTest, TestRemoteStreaming, TestPivotHelperCode, TestSolrConfigHandler, TestRandomDVFaceting, TestIndexingPerformance, TestFaceting, TestMinMaxOnMultiValuedField, DistanceUnitsTest, DateFieldTest, HdfsUnloadDistributedZkTest, TestRangeQuery, SuggestComponentTest, TestDFRSimilarityFactory, TestRebalanceLeaders, TestArbitraryIndexDir, CoreAdminRequestStatusTest, ExitableDirectoryReaderTest, TestRawResponseWriter, TestExactStatsCache, HdfsThreadLeakTest, ExternalFileFieldSortTest, FullHLLTest, TestDocumentBuilder, TestDocBasedVersionConstraints, TestCloudInspectUtil, DateMathParserTest, FastVectorHighlighterTest, LeaderFailoverAfterPartitionTest, HLLUtilTest, TestSolrIndexConfig, TestManagedResource, AnalyticsMergeStrategyTest, DebugComponentTest, ConcurrentDeleteAndCreateCollectionTest, TestSolrDeletionPolicy1, TestChildDocTransformer, TestHighFrequencyDictionaryFactory, MultiTermTest, CleanupOldIndexTest, JavabinLoaderTest, DeleteReplicaTest, CSVRequestHandlerTest, CloudExitableDirectoryReaderTest, TestDynamicFieldCollectionResource, SolrIndexConfigTest, HdfsChaosMonkeySafeLeaderTest, DeleteLastCustomShardedReplicaTest, TestNamedUpdateProcessors, TestSearcherReuse, HighlighterConfigTest, TestHdfsUpdateLog, UniqFieldsUpdateProcessorFactoryTest, HdfsDirectoryTest, AddSchemaFieldsUpdateProcessorFactoryTest, HdfsRecoveryZkTest, ClusterStateTest, DeleteInactiveReplicaTest, ChaosMonkeyNothingIsSafeTest, TestReversedWildcardFilterFactory, TestCodecSupport, TestConfigOverlay, CloudMLTQParserTest, SegmentsInfoRequestHandlerTest, BasicDistributedZkTest, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest] [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=CollectionsAPIDistributedZkTest -Dtests.seed=19C2D7FD50A4B9B2 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=sr_RS_#Latn -Dtests.timezone=Iceland -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] 2> 8701370 INFO (zkCallback-1619-thread-23-processing-n:127.0.0.1:43264__nci%2Fy-EventThread) [n:127.0.0.1:43264__nci%2Fy ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 8708129 INFO (zkCallback-1619-thread-23-processing-n:127.0.0.1:43264__nci%2Fy-EventThread) [n:127.0.0.1:43264__nci%2Fy ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] ERROR 0.00s J0 | CollectionsAPIDistributedZkTest (suite) <<< [junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space [junit4] Completed [531/531] on J0 in 6705.11s, 1 test, 2 errors <<< FAILURES! [...truncated 1 lines...] [junit4] JVM J0: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J0-20150819_163311_681.sysout [junit4] >>> JVM J0: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: Java heap space [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid27793.hprof ... [junit4] Heap dump file created [627916798 bytes in 10.806 secs] [junit4] <<< JVM J0: EOF ---- [...truncated 13 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 531 suites, 2104 tests, 1 suite-level error, 2 errors, 1 failure, 26 ignored (14 assumptions) Total time: 276 minutes 39 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
