Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/899/
2 tests failed.
FAILED:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR
Error Message:
There are still nodes recoverying - waited for 330 seconds
Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 330
seconds
at
__randomizedtesting.SeedInfo.seed([839DC8B66780B967:5D17A6CB5C22DB94]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:175)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:136)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:131)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:831)
at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:159)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:965)
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)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED:
junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not
released!!! [HdfsTransactionLog]
at __randomizedtesting.SeedInfo.seed([839DC8B66780B967]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:229)
at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10608 lines...]
[junit4] Suite:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/init-core-data-001
[junit4] 2> 944198 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[839DC8B66780B967]-worker)
[ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system
property: /
[junit4] 2> 944206 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 944217 INFO (Thread-6759) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 944217 INFO (Thread-6759) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 944317 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkTestServer start zk server on port:56345
[junit4] 2> 944317 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 944341 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 944377 INFO (zkCallback-573-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@79c6f0b9
name:ZooKeeperConnection Watcher:127.0.0.1:56345 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 944377 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 944377 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 944377 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 944392 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 944398 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 944408 INFO (zkCallback-574-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@7a66fd6 name:ZooKeeperConnection
Watcher:127.0.0.1:56345/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 944408 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 944408 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 944408 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 944411 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 944412 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 944413 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 944418 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 944418 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 944420 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 944420 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 944421 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 944421 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 944423 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 944423 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 944425 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 944425 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 944426 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 944426 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 944428 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 944428 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 944429 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 944429 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 944430 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 944431 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath:
/configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 944433 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 944433 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 944440 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 944442 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 945403 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1
[junit4] 2> 945415 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 945567 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1579806b{/,null,AVAILABLE}
[junit4] 2> 945584 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@4607f2de{HTTP/1.1,[http/1.1]}{127.0.0.1:54212}
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.Server Started @949033ms
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/tempDir-001/control/data,
hostContext=/, hostPort=54212,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores}
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001'
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr
(NoInitialContextEx)
[junit4] 2> 945585 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not
find system property or JNDI)
[junit4] 2> 945586 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 945607 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 945621 INFO (zkCallback-575-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@2487e407
name:ZooKeeperConnection Watcher:127.0.0.1:56345/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 945622 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 945622 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 945624 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 945624 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/solr.xml
[junit4] 2> 945630 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores
[junit4] 2> 945631 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer New CoreContainer 1022652640
[junit4] 2> 945631 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001]
[junit4] 2> 945632 WARN
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/lib
[junit4] 2> 945632 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] 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> 945635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 945635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 945635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 945635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56345/solr
[junit4] 2> 945635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 945636 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 945651 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 945668 INFO (zkCallback-578-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@55b7c1f3
name:ZooKeeperConnection Watcher:127.0.0.1:56345 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 945668 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 945668 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 945673 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 945680 INFO
(zkCallback-579-thread-1-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@5e46e7ea
name:ZooKeeperConnection Watcher:127.0.0.1:56345/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 945680 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 945683 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 945685 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-queue-work
[junit4] 2> 945688 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-running
[junit4] 2> 945689 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-completed
[junit4] 2> 945691 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer/collection-map-failure
[junit4] 2> 945692 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 945694 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 945694 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 945695 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 945696 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:54212_
[junit4] 2> 945696 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:54212_
[junit4] 2> 945697 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 945698 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/election
[junit4] 2> 945699 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 945699 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95137096212742148-127.0.0.1:54212_-n_0000000000
[junit4] 2> 945700 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:54212_
[junit4] 2> 945700 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath:
/overseer_elect/leader
[junit4] 2> 945701 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer Overseer
(id=95137096212742148-127.0.0.1:54212_-n_0000000000) starting
[junit4] 2> 945702 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 945713 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 945728 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 945728 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 945729 INFO
(OverseerCollectionConfigSetProcessor-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.OverseerTaskProcessor Process current queue
of overseer operations
[junit4] 2> 945738 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 945785 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 945786 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 945787 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores
[junit4] 2> 945788 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=control_collection, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 945788 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1
[junit4] 2> 945788 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 945796 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 945797 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 945812 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 945812 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:54212_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 945813 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54212";,
[junit4] 2> "node_name":"127.0.0.1:54212_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 945813 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54212";,
[junit4] 2> "node_name":"127.0.0.1:54212_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 945814 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
look for our core node name
[junit4] 2> 945814 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
control_collection
[junit4] 2> 945814 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 945815 INFO
(zkCallback-579-thread-1-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 945828 INFO
(zkCallback-579-thread-2-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 946814 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
waiting to find shard id in clusterstate for collection1
[junit4] 2> 946814 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
Check for collection zkNode:control_collection
[junit4] 2> 946817 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.ZkController
Collection zkNode exists
[junit4] 2> 946817 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader Load collection config from:
[/collections/control_collection]
[junit4] 2> 946818 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.c.ZkStateReader path=[/collections/control_collection]
[configName]=[conf1] specified config exists in ZooKeeper
[junit4] 2> 946818 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1'
[junit4] 2> 946818 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 946818 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find
system property or JNDI)
[junit4] 2> 946820 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.Config loaded
config solrconfig.xml with version 0
[junit4] 2> 946825 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.SolrConfig
current version of requestparams : -1
[junit4] 2> 946829 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.0.0
[junit4] 2> 946838 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Loaded SolrConfig: solrconfig.xml
[junit4] 2> 946844 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 947496 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Initialized with
rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 947519 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.s.IndexSchema
default search field in schema is text
[junit4] 2> 947521 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.s.IndexSchema
unique key field: id
[junit4] 2> 947540 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 947546 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 947551 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 947553 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 947553 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 947553 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from
open-exchange-rates.json
[junit4] 2> 947554 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 947554 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 947556 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/configs/conf1',
cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 947556 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 947556 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore solr.StandardDirectoryFactory
[junit4] 2> 947574 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/]
[junit4] 2> 947574 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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@5a00a510
[junit4] 2> 947574 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data
[junit4] 2> 947575 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/index/
[junit4] 2> 947575 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/index'
doesn't exist. Creating new index...
[junit4] 2> 947576 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/index
[junit4] 2> 947577 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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=49, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=77.154296875,
floorSegmentMB=1.07421875, forceMergeDeletesPctAllowed=7.018057274045346,
segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 947597 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 947597 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"nodistrib"
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"dedupe"
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain
"stored_sig"
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947615 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947616 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947616 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947616 WARN
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 947618 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 947618 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 947619 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 947622 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 947626 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
Looking for old index directories to cleanup for core collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/
[junit4] 2> 947632 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 947633 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947664 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947664 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947667 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 947667 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 947667 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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=6, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=82.9052734375,
floorSegmentMB=1.9130859375, forceMergeDeletesPctAllowed=1.2945468260882675,
segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8243429645675201
[junit4] 2> 947670 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/control-001/cores/collection1/data/index,segFN=segments_1,generation=1}
[junit4] 2> 947670 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 947670 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening Searcher@17f69840[collection1] main
[junit4] 2> 947670 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947676 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947676 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947677 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 947677 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 947677 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947677 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947677 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947678 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 947688 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 947689 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947689 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947690 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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> 947690 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ 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 1522193543025655808
[junit4] 2> 947696 INFO
(searcherExecutor-1788-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@17f69840[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 947697 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateLog Took 7.0ms to seed version buckets with highest version
1522193543025655808
[junit4] 2> 947697 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 947808 INFO
(coreLoadExecutor-1787-thread-1-processing-n:127.0.0.1:54212_)
[n:127.0.0.1:54212_ c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 947814 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 947815 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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:54212
collection:control_collection shard:shard1
[junit4] 2> 947816 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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> 947823 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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/95137096212742148-core_node1-n_0000000000
[junit4] 2> 947823 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1
and weAreReplacement=false and leaderVoteWait=300000
[junit4] 2> 947825 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 947825 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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> 947825 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:54212/collection1/
[junit4] 2> 947825 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 947825 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:54212/collection1/ has no replicas
[junit4] 2> 947826 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 947828 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 947829 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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:54212/collection1/ shard1
[junit4] 2> 947833 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 947834 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer processMessage: queueSize: 2, message
= {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 947835 INFO
(zkCallback-579-thread-2-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 947835 INFO
(zkCallback-579-thread-3-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 947838 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer processMessage: queueSize: 0, message
= {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:54212";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 947849 INFO (zkCallback-581-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@10800bdf
name:ZooKeeperConnection Watcher:127.0.0.1:56345/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 947849 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 947850 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 947850 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 947852 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause
connection loss:false
[junit4] 2> 947940 INFO (zkCallback-581-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> 947941 INFO
(zkCallback-579-thread-3-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 947941 INFO
(zkCallback-579-thread-2-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 947980 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
We are http://127.0.0.1:54212/collection1/ and leader is
http://127.0.0.1:54212/collection1/
[junit4] 2> 947980 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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:54212
[junit4] 2> 947980 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 947980 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
publishing state=active
[junit4] 2> 947980 INFO
(coreZkRegister-1781-thread-1-processing-n:127.0.0.1:54212_ x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:54212_
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> 947982 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54212";,
[junit4] 2> "node_name":"127.0.0.1:54212_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 947983 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:54212";,
[junit4] 2> "node_name":"127.0.0.1:54212_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 948085 INFO (zkCallback-581-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> 948088 INFO
(zkCallback-579-thread-3-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 948088 INFO
(zkCallback-579-thread-2-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] 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> 948556 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/cores/collection1
[junit4] 2> 948557 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001
[junit4] 2> 948590 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.Server jetty-9.3.6.v20151106
[junit4] 2> 948634 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3c1698bc{/,null,AVAILABLE}
[junit4] 2> 948634 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@3fc69874{HTTP/1.1,[http/1.1]}{127.0.0.1:36537}
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.e.j.s.Server Started @952083ms
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=36537,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/cores}
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init():
sun.misc.Launcher$AppClassLoader@4e0e2f2a
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001'
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr
(NoInitialContextEx)
[junit4] 2> 948635 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not
find system property or JNDI)
[junit4] 2> 948636 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 948637 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948654 INFO (zkCallback-582-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@b820cbd name:ZooKeeperConnection
Watcher:127.0.0.1:56345/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 948654 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948655 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 948656 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 948656 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/solr.xml
[junit4] 2> 948675 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/cores
[junit4] 2> 948675 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer New CoreContainer 1553291594
[junit4] 2> 948675 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001]
[junit4] 2> 948675 WARN
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/lib
[junit4] 2> 948676 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] 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> 948677 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 948681 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 948681 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 948681 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56345/solr
[junit4] 2> 948681 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 948682 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 948683 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 948691 INFO (zkCallback-585-thread-1) [ ]
o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@713317aa
name:ZooKeeperConnection Watcher:127.0.0.1:56345 got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 948691 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 948692 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 948695 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 948708 INFO
(zkCallback-586-thread-1-processing-n:127.0.0.1:36537_) [n:127.0.0.1:36537_
] o.a.s.c.c.ConnectionManager Watcher
org.apache.solr.common.cloud.ConnectionManager@60b8c8aa
name:ZooKeeperConnection Watcher:127.0.0.1:56345/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 948709 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.c.ConnectionManager Client is connected to
ZooKeeper
[junit4] 2> 948721 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.c.ZkStateReader Updating cluster state from
ZooKeeper...
[junit4] 2> 949734 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36537_
[junit4] 2> 949735 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.c.SolrZkClient makePath:
/live_nodes/127.0.0.1:36537_
[junit4] 2> 949737 INFO (zkCallback-581-thread-1) [ ]
o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 949737 INFO
(zkCallback-579-thread-3-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 949737 INFO
(zkCallback-579-thread-1-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 949738 INFO
(zkCallback-586-thread-1-processing-n:127.0.0.1:36537_) [n:127.0.0.1:36537_
] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected
type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live
nodes size: [1])
[junit4] 2> 949739 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 949744 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.LeaderElector Joined leadership election with
path: /overseer_elect/election/95137096212742152-127.0.0.1:36537_-n_0000000001
[junit4] 2> 949744 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.LeaderElector Watching path
/overseer_elect/election/95137096212742148-127.0.0.1:54212_-n_0000000000 to
know if I could be the leader
[junit4] 2> 949770 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CoreContainer Security conf doesn't exist.
Skipping setup for authorization module.
[junit4] 2> 949770 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 949770 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CorePropertiesLocator Looking for core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/cores
[junit4] 2> 949778 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor:
{name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=collection1, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 949778 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CorePropertiesLocator Found core collection1
in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_839DC8B66780B967-001/shard-1-001/cores/collection1
[junit4] 2> 949778 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 949780 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.s.SolrDispatchFilter
user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J0
[junit4] 2> 949780 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[839DC8B66780B967])
[n:127.0.0.1:36537_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init()
done
[junit4] 2> 949782 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:36537_)
[n:127.0.0.1:36537_ c:collection1 x:collection1] o.a.s.c.ZkController
publishing state=down
[junit4] 2> 949782 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:36537_)
[n:127.0.0.1:36537_ c:collection1 x:collection1] o.a.s.c.ZkController
numShards not found on descriptor - reading it from system property
[junit4] 2> 949784 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message
= {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36537";,
[junit4] 2> "node_name":"127.0.0.1:36537_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 949784 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1
message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:36537";,
[junit4] 2> "node_name":"127.0.0.1:36537_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 949784 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ClusterStateMutator building a new cName:
collection1
[junit4] 2> 949784 INFO
(OverseerStateUpdate-95137096212742148-127.0.0.1:54212_-n_0000000000)
[n:127.0.0.1:54212_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 949785 INFO (zkCallback-581-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 949786 INFO
(zkCallback-579-thread-3-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 949786 INFO
(zkCallback-579-thread-1-processing-n:127.0.0.1:54212_) [n:127.0.0.1:54212_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 949787 INFO
(zkCallback-586-thread-1-processing-n:127.0.0.1:36537_) [n:127.0.0.1:36537_
] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged path:/clusterstate.json], has occurred
- updating... (live nodes size: [2])
[junit4] 2> 949787 INFO
(coreLoadExecutor-1798-thread-1-processing-n:127.0.0.1:36537_)
[n:127.0.0.1:36537_ c:collection1 x
[...truncated too long message...]
rver.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1158)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:511)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1090)
[junit4] 2> at
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:375)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119)
[junit4] 2> at
org.eclipse.jetty.server.Server.handle(Server.java:517)
[junit4] 2> at
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:308)
[junit4] 2> at
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242)
[junit4] 2> at
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:261)
[junit4] 2> at
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
[junit4] 2> at
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213)
[junit4] 2> at
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:654)
[junit4] 2> at
org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:572)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> Jan 01, 2016 8:18:12 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{rnd_b=PostingsFormat(name=LuceneVarGapFixedInterval), _version_=FSTOrd50,
a_t=PostingsFormat(name=LuceneVarGapDocFreqInterval),
a_i=PostingsFormat(name=LuceneVarGapFixedInterval),
id=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{},
sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=ar_SA,
timezone=Asia/Kuala_Lumpur
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_66 (64-bit)/cpus=4,threads=2,free=128444896,total=531103744
[junit4] 2> NOTE: All tests run in this JVM: [SuggestComponentTest,
TestCloudInspectUtil, TestSolrConfigHandlerCloud, TestIndexSearcher,
TestManagedSchemaFieldTypeResource, StatsComponentTest, TestConfigOverlay,
SliceStateTest, TestDefaultStatsCache, HdfsRestartWhileUpdatingTest,
DirectUpdateHandlerTest, TestManagedResource, SuggesterTSTTest,
QueryParsingTest, TestMiniSolrCloudClusterSSL, HardAutoCommitTest,
TestSchemaManager, TestFilteredDocIdSet, TestSerializedLuceneMatchVersion,
MultiThreadedOCPTest, OverseerCollectionConfigSetProcessorTest,
SimpleFacetsTest, TestRTimerTree, DateMathParserTest, TestStressUserVersions,
BasicDistributedZk2Test, AsyncCallRequestStatusResponseTest, TestCoreDiscovery,
TestSmileRequest, TermVectorComponentDistributedTest,
BinaryUpdateRequestHandlerTest, TestStressRecovery,
TestMinMaxOnMultiValuedField, BlockCacheTest, ScriptEngineTest, TestWriterPerf,
TestFastWriter, TestConfigSetsAPI, TestClusterStateMutator,
XmlUpdateRequestHandlerTest, DocValuesMissingTest,
FullSolrCloudDistribCmdsTest, DirectSolrConnectionTest,
RegexBoostProcessorTest, LeaderInitiatedRecoveryOnCommitTest,
TestBulkSchemaConcurrent, TestSchemaResource, FacetPivotSmallTest,
TestMaxScoreQueryParser, CdcrVersionReplicationTest, BadComponentTest,
TestSolrDynamicMBean, BlockJoinFacetSimpleTest, DeleteShardTest,
TestReplicaProperties, OverseerTaskQueueTest, LeaderElectionTest,
TestJmxMonitoredMap, DocValuesMultiTest, VersionInfoTest, FullHLLTest,
CoreAdminHandlerTest, TestDistributedMissingSort, ActionThrottleTest,
TestScoreJoinQPScore, DistributedFacetPivotSmallAdvancedTest,
HdfsChaosMonkeyNothingIsSafeTest, SparseHLLTest, TestExactStatsCache,
ReplicationFactorTest, TestReqParamsAPI, ParsingFieldUpdateProcessorsTest,
TestPerFieldSimilarityClassic, TestReloadDeadlock, TestGroupingSearch,
TestFieldTypeResource, SolrCloudExampleTest, TestBulkSchemaAPI, TestRecovery,
DeleteInactiveReplicaTest, OverseerTest, TestRawTransformer,
StatelessScriptUpdateProcessorFactoryTest,
BigEndianAscendingWordSerializerTest, SolrTestCaseJ4Test,
CurrencyFieldXmlFileTest, TestSolrCLIRunExample, TestSolrQueryParser,
TestElisionMultitermQuery, SmileWriterTest, ForceLeaderTest,
TestPHPSerializedResponseWriter, SharedFSAutoReplicaFailoverTest,
UnloadDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest,
ZkSolrClientTest, ZkCLITest, AutoCommitTest, TestDistributedGrouping,
TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest,
TestStressReorder, TestReload, TestStressVersions,
DistributedTermsComponentTest, TestCoreContainer, TestSolr4Spatial,
SolrCoreTest, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest,
TestSort, BasicFunctionalityTest, TestBadConfig, SoftAutoCommitTest,
ShowFileRequestHandlerTest, SolrIndexSplitterTest, SimplePostToolTest,
AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest,
TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest,
SolrRequestParserTest, DocValuesTest, SuggesterTest, TestTrie,
SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest,
NoCacheHeaderTest, WordBreakSolrSpellCheckerTest,
SolrCoreCheckLockOnStartupTest, TestUpdate, FieldMutatingUpdateProcessorTest,
TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest,
TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest,
DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest,
LukeRequestHandlerTest, StandardRequestHandlerTest,
TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest,
FileBasedSpellCheckerTest, TermsComponentTest, RequestHandlersTest,
DocumentBuilderTest, TestIndexingPerformance, RequiredFieldsTest,
FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest,
IndexSchemaTest, TestJmxIntegration, TestCollationField, TestCSVResponseWriter,
TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter,
HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest,
AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest,
ResponseLogComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2,
SampleTest, TestDocSet, TestSearchPerf, TestConfig, OutputWriterTest,
TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest,
TestPostingsSolrHighlighter, TestLuceneMatchVersion, TestPhraseSuggestions,
SpellPossibilityIteratorTest, TestCharFilters, SynonymTokenizerTest,
TestXIncludeConfig, TestDFRSimilarityFactory,
TestLMJelinekMercerSimilarityFactory, TimeZoneUtilsTest, PluginInfoTest,
RAMDirectoryFactoryTest, TestSolrJ, TestUtils, CircularListTest,
DistributedMLTComponentTest, AssignTest, AsyncMigrateRouteKeyTest,
CdcrReplicationHandlerTest, CloudExitableDirectoryReaderTest,
ConfigSetsAPITest, DistribJoinFromCollectionTest,
OutOfBoxZkACLAndCredentialsProvidersTest,
OverriddenZkACLAndCredentialsProvidersTest, SSLMigrationTest, ShardSplitTest,
TestLeaderElectionZkExpiry, TestMiniSolrCloudCluster, TestRequestForwarding,
TestRequestStatusCollectionAPI, HdfsChaosMonkeySafeLeaderTest]
[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=HdfsChaosMonkeySafeLeaderTest -Dtests.seed=839DC8B66780B967
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt
-Dtests.locale=ar_SA -Dtests.timezone=Asia/Kuala_Lumpur -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | HdfsChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1
object(s) that were not released!!! [HdfsTransactionLog]
[junit4] > at
__randomizedtesting.SeedInfo.seed([839DC8B66780B967]:0)
[junit4] > at
org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:229)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [451/573 (2!)] on J2 in 92.26s, 1 test, 1 failure <<<
FAILURES!
[...truncated 392 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:791:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:728:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:516:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1424:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981:
There were test failures: 573 suites (8 ignored), 2284 tests, 1 suite-level
error, 1 failure, 33 ignored (18 assumptions) [seed: 839DC8B66780B967]
Total time: 206 minutes 24 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
