Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/207/
4 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([85E88260B81B20E2:5B62EC1D83B94211]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:184)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:141)
at
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:136)
at
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:856)
at
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR(LeaderInitiatedRecoveryOnShardRestartTest.java:158)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED:
org.apache.solr.store.blockcache.BlockDirectoryTest.testRandomAccessWritesLargeCache
Error Message:
Direct buffer memory
Stack Trace:
java.lang.OutOfMemoryError: Direct buffer memory
at java.nio.Bits.reserveMemory(Bits.java:693)
at java.nio.DirectByteBuffer.<init>(DirectByteBuffer.java:123)
at java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:311)
at
org.apache.solr.store.blockcache.BlockCache.<init>(BlockCache.java:68)
at
org.apache.solr.store.blockcache.BlockDirectoryTest.setUp(BlockDirectoryTest.java:119)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:941)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED:
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.testCollectionsAPI
Error Message:
Expected to see collection awhollynewcollection_0 null Last available state:
DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/9)={
"replicationFactor":"4", "shards":{ "shard1":{
"range":"80000000-d554ffff", "state":"active", "replicas":{}},
"shard2":{ "range":"d5550000-2aa9ffff", "state":"active",
"replicas":{}}, "shard3":{ "range":"2aaa0000-7fffffff",
"state":"active", "replicas":{}}}, "router":{"name":"compositeId"},
"maxShardsPerNode":"4", "autoAddReplicas":"false"}
Stack Trace:
java.lang.AssertionError: Expected to see collection awhollynewcollection_0
null
Last available state:
DocCollection(awhollynewcollection_0//collections/awhollynewcollection_0/state.json/9)={
"replicationFactor":"4",
"shards":{
"shard1":{
"range":"80000000-d554ffff",
"state":"active",
"replicas":{}},
"shard2":{
"range":"d5550000-2aa9ffff",
"state":"active",
"replicas":{}},
"shard3":{
"range":"2aaa0000-7fffffff",
"state":"active",
"replicas":{}}},
"router":{"name":"compositeId"},
"maxShardsPerNode":"4",
"autoAddReplicas":"false"}
at
__randomizedtesting.SeedInfo.seed([85E88260B81B20E2:CD9DF6D4BE280F77]:0)
at org.junit.Assert.fail(Assert.java:93)
at
org.apache.solr.cloud.SolrCloudTestCase.waitForState(SolrCloudTestCase.java:236)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:496)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
FAILED:
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.testSpecificConfigsets
Error Message:
KeeperErrorCode = NoNode for /collections/withconfigset2
Stack Trace:
org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode
for /collections/withconfigset2
at
__randomizedtesting.SeedInfo.seed([85E88260B81B20E2:A896CD3A4F3F9AEE]:0)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:111)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
at
org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testSpecificConfigsets(CollectionsAPIDistributedZkTest.java:425)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
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:811)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
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:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
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 11284 lines...]
[junit4] Suite: org.apache.solr.store.blockcache.BlockDirectoryTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.store.blockcache.BlockDirectoryTest_85E88260B81B20E2-001/init-core-data-001
[junit4] 2> 487679 INFO
(SUITE-BlockDirectoryTest-seed#[85E88260B81B20E2]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 487687 INFO
(TEST-BlockDirectoryTest.testRandomAccessWritesLargeCache-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWritesLargeCache
[junit4] 2> 489311 INFO
(TEST-BlockDirectoryTest.testRandomAccessWritesLargeCache-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWritesLargeCache
[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=BlockDirectoryTest
-Dtests.method=testRandomAccessWritesLargeCache -Dtests.seed=85E88260B81B20E2
-Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/test-data/enwiki.random.lines.txt
-Dtests.locale=id-ID -Dtests.timezone=Africa/Libreville -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 1.64s J1 |
BlockDirectoryTest.testRandomAccessWritesLargeCache <<<
[junit4] > Throwable #1: java.lang.OutOfMemoryError: Direct buffer memory
[junit4] > at java.nio.Bits.reserveMemory(Bits.java:693)
[junit4] > at
java.nio.DirectByteBuffer.<init>(DirectByteBuffer.java:123)
[junit4] > at
java.nio.ByteBuffer.allocateDirect(ByteBuffer.java:311)
[junit4] > at
org.apache.solr.store.blockcache.BlockCache.<init>(BlockCache.java:68)
[junit4] > at
org.apache.solr.store.blockcache.BlockDirectoryTest.setUp(BlockDirectoryTest.java:119)
[junit4] > at java.lang.Thread.run(Thread.java:745)Throwable #2:
java.lang.NullPointerException
[junit4] > at
org.apache.solr.store.blockcache.BlockDirectoryTest.tearDown(BlockDirectoryTest.java:131)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 489319 INFO
(TEST-BlockDirectoryTest.ensureCacheConfigurable-seed#[85E88260B81B20E2]) [
] o.a.s.SolrTestCaseJ4 ###Starting ensureCacheConfigurable
[junit4] 2> 489579 INFO
(TEST-BlockDirectoryTest.ensureCacheConfigurable-seed#[85E88260B81B20E2]) [
] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 489579 INFO
(TEST-BlockDirectoryTest.ensureCacheConfigurable-seed#[85E88260B81B20E2]) [
] o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 489579 INFO
(TEST-BlockDirectoryTest.ensureCacheConfigurable-seed#[85E88260B81B20E2]) [
] o.a.s.s.b.BlockDirectory Block cache on read is disabled
[junit4] 2> 489579 INFO
(TEST-BlockDirectoryTest.ensureCacheConfigurable-seed#[85E88260B81B20E2]) [
] o.a.s.SolrTestCaseJ4 ###Ending ensureCacheConfigurable
[junit4] 2> 489583 INFO
(TEST-BlockDirectoryTest.testRandomAccessWrites-seed#[85E88260B81B20E2]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
[junit4] 1> Total time is 3168ms
[junit4] 2> 492754 INFO
(TEST-BlockDirectoryTest.testRandomAccessWrites-seed#[85E88260B81B20E2]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
[junit4] 2> 492757 INFO
(TEST-BlockDirectoryTest.testEOF-seed#[85E88260B81B20E2]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testEOF
[junit4] 2> 492818 INFO
(TEST-BlockDirectoryTest.testEOF-seed#[85E88260B81B20E2]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testEOF
[junit4] 2> 492819 INFO
(SUITE-BlockDirectoryTest-seed#[85E88260B81B20E2]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.store.blockcache.BlockDirectoryTest_85E88260B81B20E2-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=1406, maxMBSortInHeap=7.589330986925872,
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=id-ID,
timezone=Africa/Libreville
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_102 (64-bit)/cpus=4,threads=1,free=317294960,total=496500736
[junit4] 2> NOTE: All tests run in this JVM:
[WordBreakSolrSpellCheckerTest, DocumentBuilderTest, TestHashQParserPlugin,
TestDynamicFieldResource, DateMathParserTest, CollectionsAPIDistributedZkTest,
HdfsRecoveryZkTest, TestDocBasedVersionConstraints, TestCloudManagedSchema,
SpellCheckCollatorTest, HdfsBasicDistributedZkTest, TestLuceneMatchVersion,
SpatialFilterTest, CustomCollectionTest, TestUseDocValuesAsStored2,
TestCharFilters, BlockDirectoryTest]
[junit4] Completed [49/655 (1!)] on J1 in 5.17s, 4 tests, 1 error <<<
FAILURES!
[...truncated 232 lines...]
[junit4] Suite:
org.apache.solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/init-core-data-001
[junit4] 2> 642935 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[85E88260B81B20E2]-worker)
[ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 642935 INFO
(SUITE-LeaderInitiatedRecoveryOnShardRestartTest-seed#[85E88260B81B20E2]-worker)
[ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system
property: /v/ml
[junit4] 2> 642941 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 642947 INFO (Thread-37340) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 642947 INFO (Thread-37340) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 643042 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ZkTestServer start zk server on port:45774
[junit4] 2> 643066 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 643073 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 643075 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml
to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 643076 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt
to /configs/conf1/stopwords.txt
[junit4] 2> 643077 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt
to /configs/conf1/protwords.txt
[junit4] 2> 643079 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml
to /configs/conf1/currency.xml
[junit4] 2> 643080 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml
to /configs/conf1/enumsConfig.xml
[junit4] 2> 643083 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json
to /configs/conf1/open-exchange-rates.json
[junit4] 2> 643085 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt
to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 643086 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt
to /configs/conf1/old_synonyms.txt
[junit4] 2> 643087 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractZkTestCase put
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt
to /configs/conf1/synonyms.txt
[junit4] 2> 643212 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/cores/collection1
[junit4] 2> 643213 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 643215 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@65651551{/v/ml,null,AVAILABLE}
[junit4] 2> 643223 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@311bf46e{HTTP/1.1,[http/1.1]}{127.0.0.1:50633}
[junit4] 2> 643224 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server Started @647481ms
[junit4] 2> 643224 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/tempDir-001/control/data,
hostContext=/v/ml, hostPort=50633,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/cores}
[junit4] 2> 643224 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 643230 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 6.4.0
[junit4] 2> 643230 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 643230 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 643231 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-11-22T20:28:13.079Z
[junit4] 2> 643271 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 643271 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/solr.xml
[junit4] 2> 643278 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 643279 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45774/solr
[junit4] 2> 643300 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.OverseerElectionContext I am going to
be the leader 127.0.0.1:50633_v%2Fml
[junit4] 2> 643301 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.Overseer Overseer
(id=96983219752402948-127.0.0.1:50633_v%2Fml-n_0000000000) starting
[junit4] 2> 643309 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:50633_v%2Fml
[junit4] 2> 643311 INFO
(zkCallback-676-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 643436 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/cores
[junit4] 2> 643436 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 643440 INFO
(OverseerStateUpdate-96983219752402948-127.0.0.1:50633_v%2Fml-n_0000000000)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 643459 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection x:collection1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
[junit4] 2> 643473 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 643573 WARN
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection x:collection1]
o.a.s.s.IndexSchema [collection1] default search field in schema is text.
WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 643575 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection x:collection1]
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 643589 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection x:collection1]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
collection control_collection
[junit4] 2> 643595 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/control-001/cores/collection1/data/]
[junit4] 2> 643595 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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@2ad946c0
[junit4] 2> 643597 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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=16, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=5.7138671875,
floorSegmentMB=0.2158203125, forceMergeDeletesPctAllowed=22.681022262712812,
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8516399023712865
[junit4] 2> 643606 WARN
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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> 643614 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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> 643614 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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> 643615 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 643615 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 643615 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=391712009,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 643616 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2c8a218[collection1]
main]
[junit4] 2> 643617 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO
with znodeBase: /configs/conf1
[junit4] 2> 643617 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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> 643618 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 643618 INFO
(searcherExecutor-2232-thread-1-processing-n:127.0.0.1:50633_v%2Fml
x:collection1 s:shard1 c:control_collection r:core_node1)
[n:127.0.0.1:50633_v%2Fml c:control_collection s:shard1 r:core_node1
x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2c8a218[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 643618 INFO
(coreLoadExecutor-2231-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml 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 1551731516733652992
[junit4] 2> 643629 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
c:control_collection s:shard1 r:core_node1 x:collection1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 643629 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
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> 643629 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:50633/v/ml/collection1/
[junit4] 2> 643629 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
Sync Success - now sync replicas to me
[junit4] 2> 643629 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:50633/v/ml/collection1/ has no replicas
[junit4] 2> 643634 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
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:50633/v/ml/collection1/ shard1
[junit4] 2> 643786 INFO
(coreZkRegister-2224-thread-1-processing-n:127.0.0.1:50633_v%2Fml x:collection1
s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:50633_v%2Fml
c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 643942 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 643943 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause
connection loss:false
[junit4] 2> 644054 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/cores/collection1
[junit4] 2> 644054 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001
[junit4] 2> 644055 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 644057 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@51c8c35c{/v/ml,null,AVAILABLE}
[junit4] 2> 644058 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@5941a73e{HTTP/1.1,[http/1.1]}{127.0.0.1:33827}
[junit4] 2> 644058 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server Started @648315ms
[junit4] 2> 644058 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/tempDir-001/jetty1,
solrconfig=solrconfig.xml, hostContext=/v/ml, hostPort=33827,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/cores}
[junit4] 2> 644058 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 644066 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 6.4.0
[junit4] 2> 644066 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 644066 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 644066 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-11-22T20:28:13.915Z
[junit4] 2> 644078 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 644078 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/solr.xml
[junit4] 2> 644086 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 644087 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45774/solr
[junit4] 2> 644112 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 644117 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33827_v%2Fml
[junit4] 2> 644119 INFO
(zkCallback-676-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 644121 INFO (zkCallback-680-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 644128 INFO
(zkCallback-685-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 644173 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/cores
[junit4] 2> 644173 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 644177 INFO
(OverseerStateUpdate-96983219752402948-127.0.0.1:50633_v%2Fml-n_0000000000)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 645205 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 645219 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 645316 WARN
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 645318 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 645331 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 645331 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-1-001/cores/collection1/data/]
[junit4] 2> 645331 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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@2ad946c0
[junit4] 2> 645334 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=16, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=5.7138671875,
floorSegmentMB=0.2158203125, forceMergeDeletesPctAllowed=22.681022262712812,
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8516399023712865
[junit4] 2> 645344 WARN
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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> 645355 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 645355 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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> 645356 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 645356 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 645363 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=391712009,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 645363 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@43964173[collection1] main]
[junit4] 2> 645364 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 645365 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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> 645365 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 s:shard1 r:core_node1 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 645365 INFO
(coreLoadExecutor-2242-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml c:collection1 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 1551731518565515264
[junit4] 2> 645373 INFO
(searcherExecutor-2243-thread-1-processing-n:127.0.0.1:33827_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@43964173[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 645379 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 645379 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may
be the new leader - try and sync
[junit4] 2> 645379 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33827/v/ml/collection1/
[junit4] 2> 645379 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now
sync replicas to me
[junit4] 2> 645379 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:33827/v/ml/collection1/ has no replicas
[junit4] 2> 645384 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am
the new leader: http://127.0.0.1:33827/v/ml/collection1/ shard1
[junit4] 2> 645540 INFO
(coreZkRegister-2237-thread-1-processing-n:127.0.0.1:33827_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node1) [n:127.0.0.1:33827_v%2Fml c:collection1
s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 645798 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/cores/collection1
[junit4] 2> 645799 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001
[junit4] 2> 645799 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 645802 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@737ae4d1{/v/ml,null,AVAILABLE}
[junit4] 2> 645803 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.ServerConnector Started
ServerConnector@4d84347e{HTTP/1.1,[http/1.1]}{127.0.0.1:51480}
[junit4] 2> 645804 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.e.j.s.Server Started @650062ms
[junit4] 2> 645804 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/tempDir-001/jetty2,
solrconfig=solrconfig.xml, hostContext=/v/ml, hostPort=51480,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/cores}
[junit4] 2> 645804 ERROR
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 645809 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr?
version 6.4.0
[junit4] 2> 645809 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 645809 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 645809 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-11-22T20:28:15.658Z
[junit4] 2> 645813 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 645813 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/solr.xml
[junit4] 2> 645824 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with
params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 645828 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45774/solr
[junit4] 2> 645848 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 645855 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51480_v%2Fml
[junit4] 2> 645856 INFO
(zkCallback-685-thread-1-processing-n:127.0.0.1:33827_v%2Fml)
[n:127.0.0.1:33827_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 645857 INFO (zkCallback-680-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 645857 INFO
(zkCallback-676-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 645860 INFO
(zkCallback-691-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 645939 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.CorePropertiesLocator Found 1 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/cores
[junit4] 2> 645939 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.CorePropertiesLocator Cores are:
[collection1]
[junit4] 2> 645944 INFO
(OverseerStateUpdate-96983219752402948-127.0.0.1:50633_v%2Fml-n_0000000000)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.o.ReplicaMutator Assigning new node to
shard shard=shard1
[junit4] 2> 646958 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.4.0
[junit4] 2> 646975 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 647103 WARN
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] default search field in schema is text. WARNING: Deprecated,
please use 'df' on request instead.
[junit4] 2> 647105 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 647122 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 647122 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/cores/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.LeaderInitiatedRecoveryOnShardRestartTest_85E88260B81B20E2-001/shard-2-001/cores/collection1/data/]
[junit4] 2> 647122 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ad946c0
[junit4] 2> 647124 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=16, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=5.7138671875,
floorSegmentMB=0.2158203125, forceMergeDeletesPctAllowed=22.681022262712812,
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.8516399023712865
[junit4] 2> 647134 WARN
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 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> 647146 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 647146 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 647147 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 647147 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 647148 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy:
minMergeSize=0, mergeFactor=10, maxMergeSize=391712009,
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true,
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.1]
[junit4] 2> 647150 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4857a514[collection1] main]
[junit4] 2> 647152 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 647153 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 647153 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 647154 INFO
(searcherExecutor-2254-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore
[collection1] Registered new searcher Searcher@4857a514[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 647154 INFO
(coreLoadExecutor-2253-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml c:collection1 s:shard1 r:core_node2 x:collection1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1551731520441417728
[junit4] 2> 647160 INFO
(coreZkRegister-2248-thread-1-processing-n:127.0.0.1:51480_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.c.ZkController Core needs to
recover:collection1
[junit4] 2> 647160 INFO
(updateExecutor-688-thread-1-processing-n:127.0.0.1:51480_v%2Fml x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml c:collection1
s:shard1 r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Running
recovery
[junit4] 2> 647160 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 647160 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
###### startupVersions=[[]]
[junit4] 2> 647160 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Begin buffering updates. core=[collection1]
[junit4] 2> 647161 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateLog Starting
to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 647161 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Publishing state of core [collection1] as recovering, leader is
[http://127.0.0.1:33827/v/ml/collection1/] and I am
[http://127.0.0.1:51480/v/ml/collection1/]
[junit4] 2> 647166 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Sending prep recovery command to [http://127.0.0.1:33827/v/ml]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:51480_v%252Fml&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 647176 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 647177 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 647177 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=down, localState=active,
nodeName=127.0.0.1:51480_v%2Fml, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:51480/v/ml","node_name":"127.0.0.1:51480_v%2Fml","state":"down"}
[junit4] 2> 647445 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testRestartWithAllInLIR
[junit4] 2> 647445 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish -
collection: collection1 failOnTimeout:true timeout (sec):330
[junit4] 2> 648177 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1,
shard=shard1, thisCore=collection1, leaderDoesNotNeedRecovery=false, isLeader?
true, live=true, checkLive=true, currentState=recovering, localState=active,
nodeName=127.0.0.1:51480_v%2Fml, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","base_url":"http://127.0.0.1:51480/v/ml","node_name":"127.0.0.1:51480_v%2Fml","state":"recovering"}
[junit4] 2> 648177 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 1 seconds.
[junit4] 2> 648177 INFO (qtp230087103-40620) [n:127.0.0.1:33827_v%2Fml
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:51480_v%252Fml&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=1001
[junit4] 2> 655178 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Attempting to PeerSync from [http://127.0.0.1:33827/v/ml/collection1/] -
recoveringAfterStartup=[true]
[junit4] 2> 655178 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.PeerSync PeerSync:
core=collection1 url=http://127.0.0.1:51480/v/ml START
replicas=[http://127.0.0.1:33827/v/ml/collection1/] nUpdates=100
[junit4] 2> 655182 INFO (qtp230087103-40617) [n:127.0.0.1:33827_v%2Fml
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 655182 INFO (qtp230087103-40617) [n:127.0.0.1:33827_v%2Fml
c:collection1 s:shard1 r:core_node1 x:collection1] o.a.s.c.S.Request
[collection1] webapp=/v/ml path=/get
params={distrib=false&qt=/get&getFingerprint=9223372036854775807&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 655184 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.IndexFingerprint
IndexFingerprint millis:0.0 result:{maxVersionSpecified=9223372036854775807,
maxVersionEncountered=0, maxInHash=0, versionsHash=0, numVersions=0, numDocs=0,
maxDoc=0}
[junit4] 2> 655184 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.PeerSync We are
already in sync. No need to do a PeerSync
[junit4] 2> 655184 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 655184 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
No uncommitted changes. Skipping IW.commit.
[junit4] 2> 655185 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 655185 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
PeerSync stage of recovery was successful.
[junit4] 2> 655185 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Replaying updates buffered during PeerSync.
[junit4] 2> 655185 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy No
replay needed.
[junit4] 2> 655185 INFO
(recoveryExecutor-689-thread-1-processing-n:127.0.0.1:51480_v%2Fml
x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:51480_v%2Fml
c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy
Registering as Active after recovery.
[junit4] 2> 655446 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection:
collection1
[junit4] 2> 655448 INFO (qtp230087103-40621) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=collection1&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 655460 INFO
(OverseerThreadFactory-2229-thread-1-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.OverseerCollectionMessageHandler
Executing Collection Cmd :
action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 655475 INFO (qtp230087103-40624) [n:127.0.0.1:33827_v%2Fml
] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@55276a1c
[junit4] 2> 655477 INFO (qtp2024111253-40648) [n:127.0.0.1:51480_v%2Fml
] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@54efbc1c
[junit4] 2> 655502 WARN
(zkCallback-691-thread-1-processing-n:127.0.0.1:51480_v%2Fml)
[n:127.0.0.1:51480_v%2Fml ] o.a.s.c.LeaderElector
[junit4] 2> org.apache.solr.common.SolrException: SolrCore not
found:collection1 in []
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:296)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:268)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 655506 INFO (qtp230087103-40624) [n:127.0.0.1:33827_v%2Fml
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=collection1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=34
[junit4] 2> 655518 INFO (qtp2024111253-40648) [n:127.0.0.1:51480_v%2Fml
] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader
parent node, won't remove previous leader registration.
[junit4] 2> 655521 INFO (qtp2024111253-40648) [n:127.0.0.1:51480_v%2Fml
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=collection1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=44
[junit4] 2> 656227 INFO (qtp230087103-40621) [n:127.0.0.1:33827_v%2Fml
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=collection1&action=DELETE&wt=javabin&version=2} status=0 QTime=778
[junit4] 2> 656228 INFO
(TEST-LeaderInitiatedRecoveryOnShardRestartTest.testRestartWithAllInLIR-seed#[85E88260B81B20E2])
[ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection with
stateFormat=1: all_in_lir
[junit4] 2> 656231 INFO (qtp2024111253-40645) [n:127.0.0.1:51480_v%2Fml
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=3&maxShardsPerNode=1&name=all_in_lir&action=CREATE&numShards=1&stateFormat=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 656234 INFO
(OverseerThreadFactory-2229-thread-2-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.CreateCollectionCmd Create collection
all_in_lir
[junit4] 2> 656234 INFO
(OverseerCollectionConfigSetProcessor-96983219752402948-127.0.0.1:50633_v%2Fml-n_0000000000)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 656235 INFO
(OverseerThreadFactory-2229-thread-2-processing-n:127.0.0.1:50633_v%2Fml)
[n:127.0.0.1:50633_v%2Fml ] o.a.s.c.CreateCollectionCmd Only one config set
found in zk - using it:conf1
[junit4] 2> 656456 INFO (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=all_in_lir_shard1_replica3&action=CREATE&numShards=1&collection=all_in_lir&shard=shard1&wt=javabin&version=2
[junit4] 2> 656456 INFO (qtp2024111253-40649) [n:127.0.0.1:51480_v%2Fml
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=all_in_lir_shard1_replica2&action=CREATE&numShards=1&collection=all_in_lir&shard=shard1&wt=javabin&version=2
[junit4] 2> 656458 INFO (qtp698715723-40583) [n:127.0.0.1:50633_v%2Fml
] o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf1&newCollection=true&name=all_in_lir_shard1_replica1&action=CREATE&numShards=1&collection=all_in_lir&shard=shard1&wt=javabin&version=2
[junit4] 2> 657506 INFO (qtp698715723-40583) [n:127.0.0.1:50633_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.4.0
[junit4] 2> 657506 INFO (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica3] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.4.0
[junit4] 2> 657516 INFO (qtp2024111253-40649) [n:127.0.0.1:51480_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica2] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.4.0
[junit4] 2> 657520 INFO (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica3] o.a.s.s.IndexSchema
[all_in_lir_shard1_replica3] Schema name=test
[junit4] 2> 657536 INFO (qtp698715723-40583) [n:127.0.0.1:50633_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica1] o.a.s.s.IndexSchema
[all_in_lir_shard1_replica1] Schema name=test
[junit4] 2> 657540 INFO (qtp2024111253-40649) [n:127.0.0.1:51480_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica2] o.a.s.s.IndexSchema
[all_in_lir_shard1_replica2] Schema name=test
[junit4] 2> 657672 WARN (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica3] o.a.s.s.IndexSchema
[all_in_lir_shard1_replica3] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 657674 INFO (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica3] o.a.s.s.IndexSchema Loaded
schema test/1.0 with uniqueid field id
[junit4] 2> 657704 WARN (qtp2024111253-40649) [n:127.0.0.1:51480_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica2] o.a.s.s.IndexSchema
[all_in_lir_shard1_replica2] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 657706 INFO (qtp230087103-40618) [n:127.0.0.1:33827_v%2Fml
c:all_in_lir s:shard1 x:all_in_lir_shard1_replica3] o.a.s.c.CoreContainer
Creating SolrCore 'all_in_lir_shard1_replica3' using configuration from
collection all_in_lir
[junit4] 2> 657708 INFO (qtp230087103-40618) [n:
[...truncated too long message...]
lder$1.getMetrics(MetricsSourceBuilder.java:79)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:194)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at
org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:226)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:211)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:463)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:213)
[junit4] 2> at
org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:594)
[junit4] 2> at
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
[junit4] 2> at
org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:171)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:872)
[junit4] 2> at
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1726)
[junit4] 2> at
org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1705)
[junit4] 2> at
org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:198)
[junit4] 2> at
org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:52)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[junit4] 2> at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203)
[junit4] 2> at
org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370)
[junit4] 2> at
org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729)
[junit4] 2> ... 54 more
[junit4] 2> 4361307 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[85E88260B81B20E2]-worker) [
] o.a.s.c.ZkTestServer connecting to 127.0.0.1:60489 60489
[junit4] 2> 4361307 INFO
(SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[85E88260B81B20E2]-worker) [
] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_85E88260B81B20E2-001
[junit4] 2> Nov 22, 2016 9:30:11 PM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 35 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=85, maxMBSortInHeap=6.727346258230085,
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=sl,
timezone=Asia/Nicosia
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_102 (64-bit)/cpus=4,threads=2,free=100838248,total=520093696
[junit4] 2> NOTE: All tests run in this JVM: [RollingRestartTest,
CollectionStateFormat2Test, QueryParsingTest, TestSlowCompositeReaderWrapper,
ResponseLogComponentTest, TestMergePolicyConfig, InfoHandlerTest,
CircularListTest, TestReload, TestStressReorder,
PKIAuthenticationIntegrationTest, HdfsNNFailoverTest, CollectionsAPISolrJTest,
HdfsChaosMonkeyNothingIsSafeTest, ExitableDirectoryReaderTest,
TestSolrDynamicMBean, TestPhraseSuggestions, TestIntervalFaceting,
BadComponentTest, TestOrdValues, BooleanFieldTest, OverseerRolesTest,
TestSubQueryTransformer, HLLUtilTest, TestReloadAndDeleteDocs,
CurrencyFieldXmlFileTest, HdfsTlogReplayBufferedWhileIndexingTest,
TestLeaderElectionWithEmptyReplica, TestDFRSimilarityFactory,
DeleteInactiveReplicaTest, DataDrivenBlockJoinTest, TestDistributedMissingSort,
ShardSplitTest, OverriddenZkACLAndCredentialsProvidersTest, AutoCommitTest,
AnalyticsMergeStrategyTest, TestSolrCoreSnapshots,
TestRandomCollapseQParserPlugin, TestJsonRequest, TestInfoStreamLogging,
TestDocTermOrds, OverseerTest, TestBackupRepositoryFactory, BufferStoreTest,
ZkStateWriterTest, StressHdfsTest, TestSolrCloudWithDelegationTokens,
ExplicitHLLTest, ReplaceNodeTest, ResponseHeaderTest, TestNoOpRegenerator,
TestExactStatsCache, TestRecovery, DOMUtilTest,
TestLMJelinekMercerSimilarityFactory, CdcrBootstrapTest,
TestSolrFieldCacheMBean, TestSchemaManager, StatsComponentTest,
SolrTestCaseJ4Test, DistributedQueueTest, TestDefaultSearchFieldResource,
TestWriterPerf, TestSSLRandomization, TestInitQParser, TestManagedSchema,
TestAddFieldRealTimeGet, FieldMutatingUpdateProcessorTest,
TestPerFieldSimilarityClassic, TestExtendedDismaxParser, TestIndexSearcher,
TestCloudInspectUtil, SpatialHeatmapFacetsTest, RulesTest,
VMParamsZkACLAndCredentialsProvidersTest, NoCacheHeaderTest, TestUtils,
UpdateParamsTest, MinimalSchemaTest, SimpleCollectionCreateDeleteTest,
CleanupOldIndexTest, LeaderFailureAfterFreshStartTest,
TestBlendedInfixSuggestions, DirectUpdateHandlerTest, TestTrieFacet,
TestRecoveryHdfs, TestSolrCLIRunExample, TestRandomFlRTGCloud, GraphQueryTest,
TestJsonFacetRefinement, ParsingFieldUpdateProcessorsTest,
DistribCursorPagingTest, TestDefaultStatsCache, SuggesterTSTTest,
TestDownShardTolerantSearch, CloneFieldUpdateProcessorFactoryTest,
TestClassicSimilarityFactory, TestGroupingSearch, ConnectionManagerTest,
PrimUtilsTest, TestBinaryField, TestManagedStopFilterFactory, TestCryptoKeys,
PingRequestHandlerTest, TestConfigSetsAPI, TermVectorComponentDistributedTest,
CdcrVersionReplicationTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest,
DeleteReplicaTest, ForceLeaderTest, LeaderInitiatedRecoveryOnCommitTest,
MigrateRouteKeyTest, RecoveryAfterSoftCommitTest, RestartWhileUpdatingTest,
TestClusterProperties, TestConfigSetsAPIExclusivity,
TestConfigSetsAPIZkFailure, TestExclusionRuleCollectionAccess,
TestLeaderInitiatedRecoveryThread, TestMiniSolrCloudCluster,
TestReplicaProperties, TestSolrCloudWithKerberosAlt,
TestStressCloudBlindAtomicUpdates, HdfsBasicDistributedZk2Test,
HdfsCollectionsAPIDistributedZkTest]
[junit4] Completed [516/655 (3!)] on J2 in 171.06s, 20 tests, 1 failure, 1
error <<< FAILURES!
[...truncated 61157 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
