Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/288/
3 tests failed.
FAILED:
org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings
Error Message:
finalOffset expected:<80> but was:<76>
Stack Trace:
java.lang.AssertionError: finalOffset expected:<80> but was:<76>
at
__randomizedtesting.SeedInfo.seed([23AD52513D34A04D:49F6ED40647A80BE]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:292)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:307)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:311)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:842)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:641)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:539)
at
org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:880)
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.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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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
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:368)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.handler.admin.TestApiFramework.testFramework
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([1869389B2B9DED3D:F1FF2BC2D490100]:0)
at org.apache.solr.api.V2HttpCall.getApiInfo(V2HttpCall.java:194)
at
org.apache.solr.handler.admin.TestApiFramework.invoke(TestApiFramework.java:176)
at
org.apache.solr.handler.admin.TestApiFramework.testFramework(TestApiFramework.java:123)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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:368)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest.test
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([1869389B2B9DED3D:903D0741856180C5]:0)
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest.test(HdfsWriteToMultipleCollectionsTest.java:137)
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:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
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:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 3144 lines...]
[junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
[junit4] 2> TEST FAIL: useCharFilter=true text='Tullgren, 1910</small>
\u2014 East Africa * ''[[Dolichognatha albida]]'' <small>(Simo'
[junit4] 2> Exception from random analyzer:
[junit4] 2> charfilters=
[junit4] 2>
org.apache.lucene.analysis.MockCharFilter(java.io.StringReader@3116b09c)
[junit4] 2>
org.apache.lucene.analysis.charfilter.HTMLStripCharFilter(org.apache.lucene.analysis.MockCharFilter@1aba8539)
[junit4] 2> tokenizer=
[junit4] 2>
org.apache.lucene.analysis.pattern.SimplePatternTokenizer(org.apache.lucene.util.automaton.Automaton@53b0efdc)
[junit4] 2> filters=
[junit4] 2>
org.apache.lucene.analysis.tr.TurkishLowerCaseFilter(ValidatingTokenFilter@5a4d527e
term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word)
[junit4] 2> offsetsAreCorrect=true
[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=TestRandomChains
-Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=23AD52513D34A04D
-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=und -Dtests.timezone=Europe/Zurich -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] FAILURE 24.0s J2 |
TestRandomChains.testRandomChainsWithLargeStrings <<<
[junit4] > Throwable #1: java.lang.AssertionError: finalOffset
expected:<80> but was:<76>
[junit4] > at
__randomizedtesting.SeedInfo.seed([23AD52513D34A04D:49F6ED40647A80BE]:0)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:292)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:307)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.assertTokenStreamContents(BaseTokenStreamTestCase.java:311)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:842)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:641)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:539)
[junit4] > at
org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:880)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/build/analysis/common/test/J2/temp/lucene.analysis.core.TestRandomChains_23AD52513D34A04D-001
[junit4] 2> NOTE: test params are: codec=Lucene62,
sim=RandomSimilarity(queryNorm=false,coord=crazy): {dummy=IB LL-D3(800.0)},
locale=und, timezone=Europe/Zurich
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=99578048,total=286261248
[junit4] 2> NOTE: All tests run in this JVM:
[TestEnglishMinimalStemFilterFactory, TestSerbianNormalizationFilterFactory,
TestLimitTokenCountFilterFactory, TestSegmentingTokenizerBase,
GreekAnalyzerTest, TestFrenchMinimalStemFilter,
TestDelimitedPayloadTokenFilterFactory, TestPatternReplaceCharFilter,
TestDictionary, TestTrimFilter, TestWordnetSynonymParser, TestCzechStemmer,
TokenOffsetPayloadTokenFilterTest, TestSynonymFilterFactory,
TestGermanLightStemFilterFactory, TestKeepCase, TestWordDelimiterGraphFilter,
TestRemoveDuplicatesTokenFilterFactory, TestOptionalCondition,
TestPatternReplaceFilter, NGramTokenFilterTest, TestIndonesianAnalyzer,
TestScandinavianFoldingFilter, TestKStemmer, TestCJKWidthFilterFactory,
TestPerFieldAnalyzerWrapper, TestMorphData, Test64kAffixes,
TestFrenchLightStemFilter, TestStandardFactories, TestCondition2,
TestPrefixAndSuffixAwareTokenFilter, TestCzechStemFilterFactory,
TestSoraniNormalizationFilter, TestSoraniStemFilterFactory,
TestCodepointCountFilterFactory, TestHindiAnalyzer, TestFlagLong,
TestGermanStemFilter, TestWikipediaTokenizerFactory, TestPortugueseAnalyzer,
TestKStemFilterFactory, HTMLStripCharFilterTest,
TestBrazilianStemFilterFactory, TestKeywordRepeatFilter,
DateRecognizerFilterTest, TestPatternReplaceFilterFactory, TestSnowball,
TestRandomChains]
[junit4] Completed [151/278 (1!)] on J2 in 119.01s, 2 tests, 1 failure <<<
FAILURES!
[...truncated 8284 lines...]
[junit4] Suite: org.apache.solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/init-core-data-001
[junit4] 2> 37143 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 37144 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-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> 37145 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 38057 WARN
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.h.u.NativeCodeLoader Unable to load native-hadoop library for your
platform... using builtin-java classes where applicable
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 39004 WARN
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried
hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 39213 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via
org.mortbay.log.Slf4jLog
[junit4] 2> 39235 WARN
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 39353 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 39403 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs
to ./temp/Jetty_localhost_42085_hdfs____ffc3a1/webapp
[junit4] 2> 40297 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:42085
[junit4] 2> 41918 WARN
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 41922 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 41931 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_42429_datanode____.zfxf8c/webapp
[junit4] 2> 42406 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:42429
[junit4] 2> 43085 WARN
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 43112 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log jetty-6.1.26
[junit4] 2> 43138 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Extract
jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode
to ./temp/Jetty_localhost_48147_datanode____.pur3c9/webapp
[junit4] 2> 43811 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-worker) [
] o.m.log Started
HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48147
[junit4] 2> 45605 INFO (IPC Server handler 5 on 55900) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-44b8f2fe-2e8a-439a-9b05-08f0b018f746 node
DatanodeRegistration(127.0.0.1:49046,
datanodeUuid=eb06bd9b-9a0f-4a63-afae-9932d42f5665, infoPort=60790,
infoSecurePort=0, ipcPort=37282,
storageInfo=lv=-56;cid=testClusterID;nsid=967113967;c=0), blocks: 0,
hasStaleStorage: true, processing time: 2 msecs
[junit4] 2> 45606 INFO (IPC Server handler 5 on 55900) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-f59643f9-f9ff-43a4-beba-fd4afb09a804 node
DatanodeRegistration(127.0.0.1:49046,
datanodeUuid=eb06bd9b-9a0f-4a63-afae-9932d42f5665, infoPort=60790,
infoSecurePort=0, ipcPort=37282,
storageInfo=lv=-56;cid=testClusterID;nsid=967113967;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 45606 INFO (IPC Server handler 6 on 55900) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-a41b2d8f-76a7-4e5a-912b-48090fc3250a node
DatanodeRegistration(127.0.0.1:57984,
datanodeUuid=a3aa134b-4f69-40db-852d-de670d59e3a5, infoPort=49107,
infoSecurePort=0, ipcPort=43593,
storageInfo=lv=-56;cid=testClusterID;nsid=967113967;c=0), blocks: 0,
hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 45610 INFO (IPC Server handler 6 on 55900) [ ]
BlockStateChange BLOCK* processReport: from storage
DS-3cb5a2b1-88c3-44c9-a1ff-c115af7c0ab4 node
DatanodeRegistration(127.0.0.1:57984,
datanodeUuid=a3aa134b-4f69-40db-852d-de670d59e3a5, infoPort=49107,
infoSecurePort=0, ipcPort=43593,
storageInfo=lv=-56;cid=testClusterID;nsid=967113967;c=0), blocks: 0,
hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 45961 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 45962 INFO (Thread-125) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 45962 INFO (Thread-125) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 46062 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ZkTestServer start zk server on port:45728
[junit4] 2> 46084 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46086 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/schema15.xml
to /configs/conf1/schema.xml
[junit4] 2> 46090 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46091 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46093 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46094 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46096 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46099 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46100 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46102 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46103 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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> 46256 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/control-001/cores/collection1
[junit4] 2> 46258 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 46260 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@40e281de{/,null,AVAILABLE}
[junit4] 2> 46260 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@63575fa2{HTTP/1.1,[http/1.1]}{127.0.0.1:47753}
[junit4] 2> 46260 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server Started @49550ms
[junit4] 2> 46261 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55900/hdfs__localhost_55900__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001_tempDir-002_control_data,
hostContext=/, hostPort=47753,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/control-001/cores}
[junit4] 2> 46261 ERROR
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 46261 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 46261 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 46261 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 46262 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-19T08:21:54.893Z
[junit4] 2> 46268 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 46268 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/control-001/solr.xml
[junit4] 2> 46276 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 46277 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45728/solr
[junit4] 2> 46331 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 46332 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] o.a.s.c.OverseerElectionContext I am going to be the
leader 127.0.0.1:47753_
[junit4] 2> 46333 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] o.a.s.c.Overseer Overseer
(id=97484309436497924-127.0.0.1:47753_-n_0000000000) starting
[junit4] 2> 46349 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:47753_
[junit4] 2> 46362 INFO
(zkCallback-21-thread-1-processing-n:127.0.0.1:47753_) [n:127.0.0.1:47753_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 46428 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] 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/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/control-001/cores
[junit4] 2> 46428 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:47753_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 46465 INFO
(OverseerStateUpdate-97484309436497924-127.0.0.1:47753_-n_0000000000)
[n:127.0.0.1:47753_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 47480 WARN
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 47506 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 6.5.0
[junit4] 2> 47631 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 47838 WARN
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ 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> 47855 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 47995 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 48029 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55900/solr_hdfs_home
[junit4] 2> 48029 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled
[junit4] 2> 48036 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection 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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/control-001/cores/collection1],
dataDir=[hdfs://localhost:55900/solr_hdfs_home/control_collection/core_node1/data/]
[junit4] 2> 48036 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX
Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c74ca2c
[junit4] 2> 48037 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/control_collection/core_node1/data/snapshot_metadata
[junit4] 2> 48078 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 48078 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 48078 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache
[junit4] 2> 49216 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 49232 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/control_collection/core_node1/data
[junit4] 2> 49326 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/control_collection/core_node1/data/index
[junit4] 2> 49338 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct
memory allocation set to [true]
[junit4] 2> 49338 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of
[8388608] will allocate [1] slabs and use ~[8388608] bytes
[junit4] 2> 49371 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.s.b.BlockDirectory Block cache on write is disabled
[junit4] 2> 49372 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=12, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=30.4580078125,
floorSegmentMB=0.9482421875, forceMergeDeletesPctAllowed=7.656383864441519,
segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2626329900004277
[junit4] 2> 49837 INFO (IPC Server handler 8 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49046 is
added to blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-44b8f2fe-2e8a-439a-9b05-08f0b018f746:NORMAL:127.0.0.1:49046|RBW],
ReplicaUC[[DISK]DS-a41b2d8f-76a7-4e5a-912b-48090fc3250a:NORMAL:127.0.0.1:57984|RBW]]}
size 71
[junit4] 2> 49842 INFO (IPC Server handler 2 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57984 is
added to blk_1073741825_1001 size 71
[junit4] 2> 50584 WARN
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type =
requestHandler,name = /dump,class = DumpRequestHandler,attributes =
{initParams=a, name=/dump, class=DumpRequestHandler},args =
{defaults={a=A,b=B}}}
[junit4] 2> 50760 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 50760 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 50760 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 50806 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 50806 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 50811 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=50, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=91.953125,
floorSegmentMB=0.9287109375, forceMergeDeletesPctAllowed=29.939285096133297,
segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12,
noCFSRatio=0.2725521170725631
[junit4] 2> 51024 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@5d8d3363[collection1] main]
[junit4] 2> 51027 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 51029 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 51031 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 51040 INFO
(searcherExecutor-65-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5d8d3363[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 51048 INFO
(coreLoadExecutor-64-thread-1-processing-n:127.0.0.1:47753_)
[n:127.0.0.1:47753_ c:control_collection x:collection1] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1559748956314927104
[junit4] 2> 51072 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas
found to continue.
[junit4] 2> 51072 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ 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> 51072 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:47753/collection1/
[junit4] 2> 51072 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync
replicas to me
[junit4] 2> 51072 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.SyncStrategy
http://127.0.0.1:47753/collection1/ has no replicas
[junit4] 2> 51094 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ 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:47753/collection1/ shard1
[junit4] 2> 51133 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 51134 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45728/solr ready
[junit4] 2> 51136 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection
loss:false
[junit4] 2> 51136 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2
[junit4] 2> 51195 INFO
(coreZkRegister-57-thread-1-processing-n:127.0.0.1:47753_ x:collection1
c:control_collection) [n:127.0.0.1:47753_ c:control_collection s:shard1
r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery
necessary
[junit4] 2> 51595 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001/cores/collection1
[junit4] 2> 51597 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001
[junit4] 2> 51601 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 51614 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@28ec9ddc{/,null,AVAILABLE}
[junit4] 2> 51615 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@78832145{HTTP/1.1,[http/1.1]}{127.0.0.1:51564}
[junit4] 2> 51615 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server Started @54904ms
[junit4] 2> 51615 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55900/hdfs__localhost_55900__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001_tempDir-002_jetty1,
solrconfig=solrconfig.xml, hostContext=/, hostPort=51564,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001/cores}
[junit4] 2> 51615 ERROR
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 51625 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 51625 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 51625 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 51625 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-19T08:22:00.257Z
[junit4] 2> 51657 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 51657 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001/solr.xml
[junit4] 2> 51682 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 51693 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45728/solr
[junit4] 2> 51740 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:51564_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 51743 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:51564_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 51760 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:51564_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:51564_
[junit4] 2> 51767 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 51771 INFO
(zkCallback-21-thread-4-processing-n:127.0.0.1:47753_) [n:127.0.0.1:47753_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 51774 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 52262 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:51564_ ] 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/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001/cores
[junit4] 2> 52262 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:51564_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 52635 INFO
(OverseerStateUpdate-97484309436497924-127.0.0.1:47753_-n_0000000000)
[n:127.0.0.1:47753_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 52743 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 53696 WARN
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 53696 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 53755 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 53972 WARN
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ 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> 53990 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 54048 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 54050 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55900/solr_hdfs_home
[junit4] 2> 54051 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Solr Kerberos Authentication disabled
[junit4] 2> 54051 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-1-001/cores/collection1],
dataDir=[hdfs://localhost:55900/solr_hdfs_home/collection1/core_node1/data/]
[junit4] 2> 54051 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5c74ca2c
[junit4] 2> 54055 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node1/data/snapshot_metadata
[junit4] 2> 54084 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 54084 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 54120 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 54128 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node1/data
[junit4] 2> 54327 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node1/data/index
[junit4] 2> 54336 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 54336 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 54494 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 54495 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=41,
maxMergedSegmentMB=30.4580078125, floorSegmentMB=0.9482421875,
forceMergeDeletesPctAllowed=7.656383864441519, segmentsPerTier=49.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2626329900004277
[junit4] 2> 54605 INFO (IPC Server handler 1 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57984 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-a41b2d8f-76a7-4e5a-912b-48090fc3250a:NORMAL:127.0.0.1:57984|RBW],
ReplicaUC[[DISK]DS-f59643f9-f9ff-43a4-beba-fd4afb09a804:NORMAL:127.0.0.1:49046|RBW]]}
size 0
[junit4] 2> 54608 INFO (IPC Server handler 6 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49046 is
added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-a41b2d8f-76a7-4e5a-912b-48090fc3250a:NORMAL:127.0.0.1:57984|RBW],
ReplicaUC[[DISK]DS-f59643f9-f9ff-43a4-beba-fd4afb09a804:NORMAL:127.0.0.1:49046|RBW]]}
size 0
[junit4] 2> 54638 WARN
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 54759 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 54759 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 54759 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 54782 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 54782 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 54792 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12,
maxMergedSegmentMB=91.953125, floorSegmentMB=0.9287109375,
forceMergeDeletesPctAllowed=29.939285096133297, segmentsPerTier=17.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2725521170725631
[junit4] 2> 54819 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@34a1ef75[collection1] main]
[junit4] 2> 54823 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 54824 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 54824 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 54827 INFO
(searcherExecutor-76-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@34a1ef75[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 54832 INFO
(coreLoadExecutor-75-thread-1-processing-n:127.0.0.1:51564_)
[n:127.0.0.1:51564_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1559748960283787264
[junit4] 2> 54840 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to
continue.
[junit4] 2> 54840 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ 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> 54840 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:51564/collection1/
[junit4] 2> 54840 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 54840 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51564/collection1/ has no
replicas
[junit4] 2> 54842 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 55021 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:51564/collection1/ shard1
[junit4] 2> 55138 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 55174 INFO
(coreZkRegister-70-thread-1-processing-n:127.0.0.1:51564_ x:collection1
c:collection1) [n:127.0.0.1:51564_ c:collection1 s:shard1 r:core_node1
x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 55287 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 55300 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001/cores/collection1
[junit4] 2> 55304 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001
[junit4] 2> 55305 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 55328 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2197847b{/,null,AVAILABLE}
[junit4] 2> 55329 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@24605722{HTTP/1.1,[http/1.1]}{127.0.0.1:52463}
[junit4] 2> 55329 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server Started @58618ms
[junit4] 2> 55329 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55900/hdfs__localhost_55900__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001_tempDir-002_jetty2,
solrconfig=solrconfig.xml, hostContext=/, hostPort=52463,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001/cores}
[junit4] 2> 55329 ERROR
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 55342 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 55342 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 55342 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 55343 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-19T08:22:03.975Z
[junit4] 2> 55456 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 55456 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001/solr.xml
[junit4] 2> 55465 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 55470 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45728/solr
[junit4] 2> 55545 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:52463_ ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (2)
[junit4] 2> 55547 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:52463_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 55549 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:52463_ ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52463_
[junit4] 2> 55573 INFO
(zkCallback-37-thread-1-processing-n:127.0.0.1:52463_) [n:127.0.0.1:52463_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 55574 INFO
(zkCallback-21-thread-3-processing-n:127.0.0.1:47753_) [n:127.0.0.1:47753_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 55573 INFO (zkCallback-25-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 55573 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 55680 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 56132 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:52463_ ] 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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001/cores
[junit4] 2> 56132 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D])
[n:127.0.0.1:52463_ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 56194 INFO
(OverseerStateUpdate-97484309436497924-127.0.0.1:47753_-n_0000000000)
[n:127.0.0.1:47753_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard
shard=shard1
[junit4] 2> 56298 INFO
(zkCallback-37-thread-1-processing-n:127.0.0.1:52463_) [n:127.0.0.1:52463_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 56298 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 57259 WARN
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.Config Beginning
with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 57267 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.5.0
[junit4] 2> 57593 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.s.IndexSchema
[collection1] Schema name=test
[junit4] 2> 58156 WARN
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ 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> 58163 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded
schema test/1.6 with uniqueid field id
[junit4] 2> 58247 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 58247 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
solr.hdfs.home=hdfs://localhost:55900/solr_hdfs_home
[junit4] 2> 58247 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Solr Kerberos Authentication disabled
[junit4] 2> 58247 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-2-001/cores/collection1],
dataDir=[hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data/]
[junit4] 2> 58248 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX
monitoring is enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@5c74ca2c
[junit4] 2> 58248 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data/snapshot_metadata
[junit4] 2> 58310 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 58310 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 58326 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 58328 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data
[junit4] 2> 58651 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
creating directory factory for path
hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data/index
[junit4] 2> 58735 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Number of slabs of block cache [1] with direct memory allocation set to [true]
[junit4] 2> 58735 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.HdfsDirectoryFactory
Block cache target memory usage, slab size of [8388608] will allocate [1] slabs
and use ~[8388608] bytes
[junit4] 2> 58787 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.s.b.BlockDirectory
Block cache on write is disabled
[junit4] 2> 58787 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=41,
maxMergedSegmentMB=30.4580078125, floorSegmentMB=0.9482421875,
forceMergeDeletesPctAllowed=7.656383864441519, segmentsPerTier=49.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2626329900004277
[junit4] 2> 58900 INFO (IPC Server handler 3 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:49046 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-a41b2d8f-76a7-4e5a-912b-48090fc3250a:NORMAL:127.0.0.1:57984|RBW],
ReplicaUC[[DISK]DS-44b8f2fe-2e8a-439a-9b05-08f0b018f746:NORMAL:127.0.0.1:49046|FINALIZED]]}
size 0
[junit4] 2> 58902 INFO (IPC Server handler 1 on 55900) [ ]
BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:57984 is
added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-44b8f2fe-2e8a-439a-9b05-08f0b018f746:NORMAL:127.0.0.1:49046|FINALIZED],
ReplicaUC[[DISK]DS-3cb5a2b1-88c3-44c9-a1ff-c115af7c0ab4:NORMAL:127.0.0.1:57984|FINALIZED]]}
size 0
[junit4] 2> 58933 WARN
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.c.RequestHandlers
INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class
= DumpRequestHandler,attributes = {initParams=a, name=/dump,
class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 59123 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.UpdateHandler Using
UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog
[junit4] 2> 59123 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100
maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 59123 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.HdfsUpdateLog
Initializing HdfsUpdateLog: tlogDfsReplication=2
[junit4] 2> 59137 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 59137 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 59141 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.RandomMergePolicy
RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy:
[TieredMergePolicy: maxMergeAtOnce=50, maxMergeAtOnceExplicit=12,
maxMergedSegmentMB=91.953125, floorSegmentMB=0.9287109375,
forceMergeDeletesPctAllowed=29.939285096133297, segmentsPerTier=17.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2725521170725631
[junit4] 2> 59175 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@13689fc9[collection1] main]
[junit4] 2> 59177 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf1
[junit4] 2> 59177 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 59177 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 59180 INFO
(searcherExecutor-87-thread-1-processing-n:127.0.0.1:52463_ x:collection1
c:collection1) [n:127.0.0.1:52463_ c:collection1 x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@13689fc9[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 59186 INFO
(coreLoadExecutor-86-thread-1-processing-n:127.0.0.1:52463_)
[n:127.0.0.1:52463_ c:collection1 x:collection1] o.a.s.u.UpdateLog Could not
find max version in index or recent updates, using new clock 1559748964849287168
[junit4] 2> 59197 INFO
(coreZkRegister-81-thread-1-processing-n:127.0.0.1:52463_ x:collection1
c:collection1) [n:127.0.0.1:52463_ c:collection1 s:shard1 r:core_node2
x:collection1] o.a.s.c.ZkController Core needs to recover:collection1
[junit4] 2> 59200 INFO
(updateExecutor-34-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 59218 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Starting recovery process.
recoveringAfterStartup=true
[junit4] 2> 59218 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 59219 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Begin buffering updates.
core=[collection1]
[junit4] 2> 59219 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.u.UpdateLog Starting to buffer updates.
HDFSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 59219 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ 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:51564/collection1/]
and I am [http://127.0.0.1:52463/collection1/]
[junit4] 2> 59227 INFO
(zkCallback-31-thread-1-processing-n:127.0.0.1:51564_) [n:127.0.0.1:51564_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 59227 INFO
(zkCallback-37-thread-1-processing-n:127.0.0.1:52463_) [n:127.0.0.1:52463_ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 59238 INFO
(recoveryExecutor-35-thread-1-processing-n:127.0.0.1:52463_ x:collection1
s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:52463_ c:collection1 s:shard1
r:core_node2 x:collection1] o.a.s.c.RecoveryStrategy Sending prep recovery
command to [http://127.0.0.1:51564]; [WaitForState:
action=PREPRECOVERY&core=collection1&nodeName=127.0.0.1:52463_&coreNodeName=core_node2&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 59275 INFO (qtp1266166898-329) [n:127.0.0.1:51564_ ]
o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node2, state:
recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true
[junit4] 2> 59277 INFO (qtp1266166898-329) [n:127.0.0.1:51564_ ]
o.a.s.h.a.PrepRecoveryOp Will wait a max of 183 seconds to see collection1
(shard1 of collection1) have state: recovering
[junit4] 2> 59277 INFO (qtp1266166898-329) [n:127.0.0.1:51564_ ]
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:52463_, coreNodeName=core_node2,
onlyIfActiveCheckResult=false, nodeProps:
core_node2:{"core":"collection1","dataDir":"hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data/","base_url":"http://127.0.0.1:52463","node_name":"127.0.0.1:52463_","state":"recovering","ulogDir":"hdfs://localhost:55900/solr_hdfs_home/collection1/core_node2/data/tlog"}
[junit4] 2> 59277 INFO (qtp1266166898-329) [n:127.0.0.1:51564_ ]
o.a.s.h.a.PrepRecoveryOp Waited coreNodeName: core_node2, state: recovering,
checkLive: true, onlyIfLeader: true for: 0 seconds.
[junit4] 2> 59278 INFO (qtp1266166898-329) [n:127.0.0.1:51564_ ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:52463_&onlyIfLeaderActive=true&core=collection1&coreNodeName=core_node2&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=0 QTime=3
[junit4] 2> 59750 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
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/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-3-001/cores/collection1
[junit4] 2> 59758 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-3-001
[junit4] 2> 59780 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 59971 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7114760c{/,null,AVAILABLE}
[junit4] 2> 59996 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@248a316c{HTTP/1.1,[http/1.1]}{127.0.0.1:60411}
[junit4] 2> 59996 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.e.j.s.Server Started @63286ms
[junit4] 2> 59996 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties:
{solr.data.dir=hdfs://localhost:55900/hdfs__localhost_55900__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_checkout_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001_tempDir-002_jetty3,
solrconfig=solrconfig.xml, hostContext=/, hostPort=60411,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001/shard-3-001/cores}
[junit4] 2> 59996 ERROR
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 59997 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 59997 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 59997 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 59997 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-19T08:22:08.629Z
[junit4] 2> 60028 INFO
(TEST-HdfsWriteToMultipleCollectionsTest.test-seed#[1869389B2B9DED3D]) [ ]
o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in
ZooKeeper)
[junit4] 2> 60028 INFO (TEST-HdfsWriteToMultipleCollectionsTe
[...truncated too long message...]
)
[junit4] 2> ... 53 more
[junit4] 2> 278031 INFO
(SUITE-HdfsWriteToMultipleCollectionsTest-seed#[1869389B2B9DED3D]-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.HdfsWriteToMultipleCollectionsTest_1869389B2B9DED3D-001
[junit4] 2> Feb 19, 2017 8:25:46 AM
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):
{rnd_b=PostingsFormat(name=LuceneFixedGap),
_version_=PostingsFormat(name=Memory doPackFST= false),
a_t=PostingsFormat(name=Memory doPackFST= false),
a_i=PostingsFormat(name=LuceneFixedGap),
id=PostingsFormat(name=LuceneFixedGap)}, docValues:{}, maxPointsInLeafNode=701,
maxMBSortInHeap=5.609873712501604,
sim=RandomSimilarity(queryNorm=false,coord=yes): {}, locale=es-SV,
timezone=Europe/Zaporozhye
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=6,free=74038824,total=388497408
[junit4] 2> NOTE: All tests run in this JVM:
[LeaderElectionContextKeyTest, AutoCommitTest,
HdfsWriteToMultipleCollectionsTest]
[junit4] Completed [14/692 (1!)] on J2 in 250.84s, 1 test, 1 failure <<<
FAILURES!
[...truncated 1722 lines...]
[junit4] Suite: org.apache.solr.handler.admin.TestApiFramework
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/solr/build/solr-core/test/J1/temp/solr.handler.admin.TestApiFramework_1869389B2B9DED3D-001/init-core-data-001
[junit4] 2> 4406078 INFO
(SUITE-TestApiFramework-seed#[1869389B2B9DED3D]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 4406080 INFO
(SUITE-TestApiFramework-seed#[1869389B2B9DED3D]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 4406082 INFO
(TEST-TestApiFramework.testTrailingTemplatePaths-seed#[1869389B2B9DED3D]) [
] o.a.s.SolrTestCaseJ4 ###Starting testTrailingTemplatePaths
[junit4] 2> 4406083 INFO
(TEST-TestApiFramework.testTrailingTemplatePaths-seed#[1869389B2B9DED3D]) [
] o.a.s.SolrTestCaseJ4 ###Ending testTrailingTemplatePaths
[junit4] 2> 4406091 INFO
(TEST-TestApiFramework.testFramework-seed#[1869389B2B9DED3D]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testFramework
[junit4] 2> 4406173 INFO
(TEST-TestApiFramework.testFramework-seed#[1869389B2B9DED3D]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testFramework
[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=TestApiFramework
-Dtests.method=testFramework -Dtests.seed=1869389B2B9DED3D -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=mk-MK -Dtests.timezone=Africa/Accra -Dtests.asserts=true
-Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.10s J1 | TestApiFramework.testFramework <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at
__randomizedtesting.SeedInfo.seed([1869389B2B9DED3D:F1FF2BC2D490100]:0)
[junit4] > at
org.apache.solr.api.V2HttpCall.getApiInfo(V2HttpCall.java:194)
[junit4] > at
org.apache.solr.handler.admin.TestApiFramework.invoke(TestApiFramework.java:176)
[junit4] > at
org.apache.solr.handler.admin.TestApiFramework.testFramework(TestApiFramework.java:123)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 4406183 INFO
(SUITE-TestApiFramework-seed#[1869389B2B9DED3D]-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.handler.admin.TestApiFramework_1869389B2B9DED3D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62),
sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=mk-MK,
timezone=Africa/Accra
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=147352128,total=532152320
[junit4] 2> NOTE: All tests run in this JVM: [HighlighterMaxOffsetTest,
TestNumericTerms32, SparseHLLTest, TestFastOutputStream,
DistributedVersionInfoTest, TestSerializedLuceneMatchVersion,
TestSystemIdResolver, TestGraphTermsQParserPlugin, TestRebalanceLeaders,
TlogReplayBufferedWhileIndexingTest, HdfsRecoverLeaseTest,
TestRequestStatusCollectionAPI, TestCloudDeleteByQuery,
HdfsChaosMonkeySafeLeaderTest, AddBlockUpdateTest, BasicFunctionalityTest,
MoreLikeThisHandlerTest, TermsComponentTest, CollectionTooManyReplicasTest,
TestCloudSchemaless, TestCollectionAPI, RAMDirectoryFactoryTest,
TestRestoreCore, CollectionsAPIDistributedZkTest, ChaosMonkeySafeLeaderTest,
ClusterStateUpdateTest, TestBlendedInfixSuggestions, CursorMarkTest,
CloudExitableDirectoryReaderTest, TestCollapseQParserPlugin,
TermVectorComponentTest, ManagedSchemaRoundRobinCloudTest,
TestQuerySenderListener, SharedFSAutoReplicaFailoverUtilsTest, TestFiltering,
DistributedFacetExistsSmallTest, FileUtilsTest, TestSurroundQueryParser,
TestCSVResponseWriter, TestSimpleTrackingShardHandler, HighlighterTest,
DateMathParserTest, TestShardHandlerFactory, ConvertedLegacyTest,
TestQueryTypes, TestSubQueryTransformerDistrib, IndexSchemaTest,
UUIDUpdateProcessorFallbackTest, TestReloadDeadlock,
HdfsUnloadDistributedZkTest, SpellingQueryConverterTest,
DirectSolrSpellCheckerTest, TestManagedSchemaAPI,
ConcurrentDeleteAndCreateCollectionTest, TestDefaultStatsCache, TestOrdValues,
DistributedIntervalFacetingTest, WrapperMergePolicyFactoryTest, CoreSorterTest,
CreateCollectionCleanupTest, SpellCheckCollatorTest,
LeaderFailureAfterFreshStartTest, MultiTermTest, JsonLoaderTest,
HdfsBasicDistributedZk2Test, RestartWhileUpdatingTest, DistanceFunctionTest,
TestExactStatsCache, BufferStoreTest, TestUseDocValuesAsStored2, TestUpdate,
TestDynamicFieldResource, GraphQueryTest, TestUniqueKeyFieldResource,
TestLMDirichletSimilarityFactory, LoggingHandlerTest,
TestOnReconnectListenerSupport, WordBreakSolrSpellCheckerTest,
TestMiniSolrCloudCluster, TestJsonFacetRefinement,
TestLeaderInitiatedRecoveryThread, RequestLoggingTest,
ShufflingReplicaListTransformerTest, TestSearchPerf, TestConfigSets,
OverseerTaskQueueTest, TestInitParams, ActionThrottleTest,
OutOfBoxZkACLAndCredentialsProvidersTest, TestSchemaResource,
DistributedFacetPivotSmallAdvancedTest, TestCursorMarkWithoutUniqueKey,
TestObjectReleaseTracker, TestRTGBase, TestBulkSchemaConcurrent,
TestLuceneMatchVersion, TestRuleBasedAuthorizationPlugin,
DistribDocExpirationUpdateProcessorTest, HttpPartitionTest,
TestClassicSimilarityFactory, SolrInfoMBeanTest,
TestCopyFieldCollectionResource, DataDrivenBlockJoinTest, SampleTest, TestTrie,
TestPivotHelperCode, TestRawResponseWriter, ZkSolrClientTest,
BlockJoinFacetDistribTest, RemoteQueryErrorTest, TestElisionMultitermQuery,
TestConfigOverlay, TestDocTermOrds, SoftAutoCommitTest,
LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest,
FullSolrCloudDistribCmdsTest, TestReplicationHandler,
LeaderInitiatedRecoveryOnCommitTest, MissingSegmentRecoveryTest,
MultiThreadedOCPTest, SaslZkACLProviderTest, ShardSplitTest,
SolrCloudExampleTest, SolrXmlInZkTest, TestCloudInspectUtil,
TestCloudPivotFacet, TestCloudPseudoReturnFields, TestCloudRecovery,
TestDistribDocBasedVersion, TestLeaderElectionWithEmptyReplica,
TestLeaderElectionZkExpiry, TestReplicaProperties, TestSSLRandomization,
TestShortCircuitedRequests, TestSolrCloudWithDelegationTokens,
TestSolrCloudWithKerberosAlt, TestTolerantUpdateProcessorRandomCloud,
TriLevelCompositeIdRoutingTest, HdfsNNFailoverTest, HdfsThreadLeakTest,
StressHdfsTest, HdfsDirectoryFactoryTest, TestConfigSetImmutable,
TestConfigSetProperties, TestDynamicLoading, TestBlobHandler,
TestReplicationHandlerBackup, TestSolrConfigHandlerCloud, TestApiFramework]
[junit4] Completed [551/692 (2!)] on J1 in 0.12s, 2 tests, 1 error <<<
FAILURES!
[...truncated 38921 lines...]
check-lib-versions:
[echo] Lib versions check under:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/..
[libversions] :: loading settings :: file =
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/top-level-ivy-settings.xml
[libversions] ORPHAN coordinate key '/cglib/cglib-nodep' in
ivy-versions.properties is not found in any ivy.xml file.
[libversions] Checked that ivy-versions.properties and
ivy-ignore-conflicts.properties have lexically sorted '/org/name' keys and no
duplicates or orphans.
[libversions] Scanned 49 ivy.xml files for rev="${/org/name}" format.
[libversions] Found 0 indirect dependency version conflicts.
[libversions] Completed in 0.88s., 1 error(s).
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/build.xml:783:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/build.xml:122:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/build.xml:108:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/checkout/lucene/tools/custom-tasks.xml:108:
Lib versions check failed. Check the logs.
Total time: 248 minutes 57 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
