You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/05/09 23:15:08 UTC
[JENKINS] Lucene-Solr-NightlyTests-6.x - Build # 60 - Failure
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/60/
2 tests failed.
FAILED: org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat.testBinaryFixedLengthVsStoredFields
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([771AFF4B9A67369E]:0)
FAILED: junit.framework.TestSuite.org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([771AFF4B9A67369E]:0)
Build Log:
[...truncated 1721 lines...]
[junit4] Suite: org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
[junit4] 2> May 09, 2016 4:14:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl$2 evaluate
[junit4] 2> WARNING: Suite execution timed out: org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat
[junit4] 2> 1) Thread[id=4934, name=TEST-TestPerFieldDocValuesFormat.testBinaryFixedLengthVsStoredFields-seed#[771AFF4B9A67369E], state=TIMED_WAITING, group=TGRP-TestPerFieldDocValuesFormat]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:4328)
[junit4] 2> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1802)
[junit4] 2> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1736)
[junit4] 2> at org.apache.lucene.index.RandomIndexWriter.forceMerge(RandomIndexWriter.java:421)
[junit4] 2> at org.apache.lucene.index.BaseDocValuesFormatTestCase.doTestBinaryVsStoredFields(BaseDocValuesFormatTestCase.java:1392)
[junit4] 2> at org.apache.lucene.index.BaseDocValuesFormatTestCase.testBinaryFixedLengthVsStoredFields(BaseDocValuesFormatTestCase.java:1412)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 2) Thread[id=4933, name=SUITE-TestPerFieldDocValuesFormat-seed#[771AFF4B9A67369E], state=RUNNABLE, group=TGRP-TestPerFieldDocValuesFormat]
[junit4] 2> at java.lang.Thread.getStackTrace(Thread.java:1552)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:688)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$4.run(ThreadLeakControl.java:685)
[junit4] 2> at java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getStackTrace(ThreadLeakControl.java:685)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.getThreadsWithTraces(ThreadLeakControl.java:701)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.formatThreadStacksFull(ThreadLeakControl.java:681)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.access$1000(ThreadLeakControl.java:64)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$2.evaluate(ThreadLeakControl.java:414)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:681)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.access$200(RandomizedRunner.java:140)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:591)
[junit4] 2> 3) Thread[id=9, name=JUnit4-serializer-daemon, state=TIMED_WAITING, group=main]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.events.Serializer$1.run(Serializer.java:47)
[junit4] 2> 4) Thread[id=1, name=main, state=WAITING, group=main]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at java.lang.Thread.join(Thread.java:1245)
[junit4] 2> at java.lang.Thread.join(Thread.java:1319)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:601)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.run(RandomizedRunner.java:450)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.execute(SlaveMain.java:243)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMain.main(SlaveMain.java:354)
[junit4] 2> at com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe.main(SlaveMainSafe.java:10)
[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=TestPerFieldDocValuesFormat -Dtests.method=testBinaryFixedLengthVsStoredFields -Dtests.seed=771AFF4B9A67369E -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=en -Dtests.timezone=US/Arizona -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 7029s J2 | TestPerFieldDocValuesFormat.testBinaryFixedLengthVsStoredFields <<<
[junit4] > Throwable #1: java.lang.Exception: Test abandoned because suite timeout was reached.
[junit4] > at __randomizedtesting.SeedInfo.seed([771AFF4B9A67369E]:0)
[junit4] 2> May 09, 2016 4:14:36 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> May 09, 2016 4:14:56 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.lucene.codecs.perfield.TestPerFieldDocValuesFormat:
[junit4] 2> 1) Thread[id=4934, name=TEST-TestPerFieldDocValuesFormat.testBinaryFixedLengthVsStoredFields-seed#[771AFF4B9A67369E], state=TIMED_WAITING, group=TGRP-TestPerFieldDocValuesFormat]
[junit4] 2> at java.lang.Object.wait(Native Method)
[junit4] 2> at org.apache.lucene.index.IndexWriter.doWait(IndexWriter.java:4328)
[junit4] 2> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1802)
[junit4] 2> at org.apache.lucene.index.IndexWriter.forceMerge(IndexWriter.java:1736)
[junit4] 2> at org.apache.lucene.index.RandomIndexWriter.forceMerge(RandomIndexWriter.java:421)
[junit4] 2> at org.apache.lucene.index.BaseDocValuesFormatTestCase.doTestBinaryVsStoredFields(BaseDocValuesFormatTestCase.java:1392)
[junit4] 2> at org.apache.lucene.index.BaseDocValuesFormatTestCase.testBinaryFixedLengthVsStoredFields(BaseDocValuesFormatTestCase.java:1412)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> May 09, 2016 4:14:56 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=4934, name=TEST-TestPerFieldDocValuesFormat.testBinaryFixedLengthVsStoredFields-seed#[771AFF4B9A67369E], state=TIMED_WAITING, group=TGRP-TestPerFieldDocValuesFormat]
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/core/test/J2/temp/lucene.codecs.perfield.TestPerFieldDocValuesFormat_771AFF4B9A67369E-001
[junit4] 2> May 09, 2016 11:14:56 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, maxPointsInLeafNode=547, maxMBSortInHeap=7.022806089989635, sim=RandomSimilarity(queryNorm=true,coord=no): {fieldname=IB SPL-D2, docId=BM25(k1=1.2,b=0.75), id=IB LL-LZ(0.3)}, locale=en, timezone=US/Arizona
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=202107504,total=448790528
[junit4] 2> NOTE: All tests run in this JVM: [TestNumericUtils, TestSortedSetSortField, TestTieredMergePolicy, TestIntBlockPool, TestDirectoryReader, TestTryDelete, TestSpanNearQuery, TestSort, Test2BBinaryDocValues, TestBytesRefHash, TestCodecHoldsOpenFiles, TestBooleanOr, TestComplexExplanationsOfNonMatches, TestTermVectorsReader, TestSparseFixedBitDocIdSet, TestIntsRef, TestIndexInput, Test2BPoints, TestDemo, TestSearchForDuplicates, TestNumericTokenStream, TestNumericRangeQuery32, TestPrefixQuery, TestIndexWriterConfig, TestTermsEnum2, TestRoaringDocIdSet, TestPackedTokenAttributeImpl, TestRollingUpdates, TestAttributeSource, TestIndexWriterReader, TestBM25Similarity, TestNot, TestDocValuesScoring, TestExceedMaxTermLength, TestDateTools, TestLiveFieldValues, TestField, TestIndexWriterOnDiskFull, TestMultiThreadTermVectors, TestFilterDirectory, TestCustomSearcherSort, TestFilterDirectoryReader, TestMathUtil, TestLucene50TermVectorsFormat, TestForUtil, TestStressIndexing2, TestTimSorter, TestReadOnlyIndex, TestApproximationSearchEquivalence, TestFrequencyTrackingRingBuffer, TestAllFilesCheckIndexHeader, TestBufferedIndexInput, TestSloppyPhraseQuery, TestTopDocsCollector, TestSpans, TestLazyProxSkipping, TestNRTThreads, TestSearch, TestPrefixCodedTerms, TestSortRescorer, TestGeoUtils, TestParallelReaderEmptyIndex, TestIntroSorter, TestMultiMMap, TestConcurrentMergeScheduler, TestPointQueries, TestCachingCollector, TestIndexCommit, TestNorms, TestIndexFileDeleter, TestIndexWriter, TestPerFieldDocValuesFormat]
[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=TestPerFieldDocValuesFormat -Dtests.seed=771AFF4B9A67369E -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=en -Dtests.timezone=US/Arizona -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | TestPerFieldDocValuesFormat (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([771AFF4B9A67369E]:0)
[junit4] Completed [419/419 (1!)] on J2 in 7220.09s, 96 tests, 2 errors <<< FAILURES!
[...truncated 12 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build.xml:50: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 419 suites (1 ignored), 3506 tests, 1 suite-level error, 1 error, 38 ignored (34 assumptions) [seed: 771AFF4B9A67369E]
Total time: 135 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-NightlyTests-6.x - Build # 62 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/62/
3 tests failed.
FAILED: org.apache.solr.hadoop.MorphlineBasicMiniMRTest.mrRun
Error Message:
Failed on local exception: java.io.IOException: Broken pipe; Host Details : local host is: "lucene1-us-west/10.41.0.5"; destination host is: "lucene1-us-west.apache.org":41749;
Stack Trace:
java.io.IOException: Failed on local exception: java.io.IOException: Broken pipe; Host Details : local host is: "lucene1-us-west/10.41.0.5"; destination host is: "lucene1-us-west.apache.org":41749;
at __randomizedtesting.SeedInfo.seed([898818BB2E65B20E:87DAACB52FF38001]:0)
at org.apache.hadoop.net.NetUtils.wrapException(NetUtils.java:772)
at org.apache.hadoop.ipc.Client.call(Client.java:1472)
at org.apache.hadoop.ipc.Client.call(Client.java:1399)
at org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:232)
at com.sun.proxy.$Proxy110.getClusterMetrics(Unknown Source)
at org.apache.hadoop.yarn.api.impl.pb.client.ApplicationClientProtocolPBClientImpl.getClusterMetrics(ApplicationClientProtocolPBClientImpl.java:202)
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 org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:187)
at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102)
at com.sun.proxy.$Proxy111.getClusterMetrics(Unknown Source)
at org.apache.hadoop.yarn.client.api.impl.YarnClientImpl.getYarnClusterMetrics(YarnClientImpl.java:461)
at org.apache.hadoop.mapred.ResourceMgrDelegate.getClusterMetrics(ResourceMgrDelegate.java:151)
at org.apache.hadoop.mapred.YARNRunner.getClusterMetrics(YARNRunner.java:179)
at org.apache.hadoop.mapreduce.Cluster.getClusterStatus(Cluster.java:246)
at org.apache.hadoop.mapred.JobClient$3.run(JobClient.java:719)
at org.apache.hadoop.mapred.JobClient$3.run(JobClient.java:717)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:422)
at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1628)
at org.apache.hadoop.mapred.JobClient.getClusterStatus(JobClient.java:717)
at org.apache.solr.hadoop.MapReduceIndexerTool.run(MapReduceIndexerTool.java:645)
at org.apache.solr.hadoop.MapReduceIndexerTool.run(MapReduceIndexerTool.java:608)
at org.apache.hadoop.util.ToolRunner.run(ToolRunner.java:70)
at org.apache.solr.hadoop.MorphlineBasicMiniMRTest.mrRun(MorphlineBasicMiniMRTest.java:364)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.io.IOException: Broken pipe
at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)
at sun.nio.ch.IOUtil.write(IOUtil.java:65)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471)
at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:63)
at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:159)
at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:117)
at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
at java.io.DataOutputStream.flush(DataOutputStream.java:123)
at org.apache.hadoop.ipc.Client$Connection$3.run(Client.java:1030)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
FAILED: junit.framework.TestSuite.org.apache.solr.hadoop.MorphlineBasicMiniMRTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.hadoop.MorphlineBasicMiniMRTest: 1) Thread[id=443, name=Node Status Updater, state=TIMED_WAITING, group=TGRP-MorphlineBasicMiniMRTest] at java.lang.Object.wait(Native Method) at org.apache.hadoop.yarn.server.nodemanager.NodeStatusUpdaterImpl$1.run(NodeStatusUpdaterImpl.java:659) at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.hadoop.MorphlineBasicMiniMRTest:
1) Thread[id=443, name=Node Status Updater, state=TIMED_WAITING, group=TGRP-MorphlineBasicMiniMRTest]
at java.lang.Object.wait(Native Method)
at org.apache.hadoop.yarn.server.nodemanager.NodeStatusUpdaterImpl$1.run(NodeStatusUpdaterImpl.java:659)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([898818BB2E65B20E]:0)
FAILED: org.apache.solr.hadoop.MorphlineGoLiveMiniMRTest.test
Error Message:
Failed on local exception: java.io.IOException: Connection reset by peer; Host Details : local host is: "lucene1-us-west/10.41.0.5"; destination host is: "lucene1-us-west.apache.org":54210;
Stack Trace:
java.io.IOException: Failed on local exception: java.io.IOException: Connection reset by peer; Host Details : local host is: "lucene1-us-west/10.41.0.5"; destination host is: "lucene1-us-west.apache.org":54210;
at __randomizedtesting.SeedInfo.seed([898818BB2E65B20E:1DC27618099DFF6]:0)
at org.apache.hadoop.net.NetUtils.wrapException(NetUtils.java:772)
at org.apache.hadoop.ipc.Client.call(Client.java:1472)
at org.apache.hadoop.ipc.Client.call(Client.java:1399)
at org.apache.hadoop.ipc.ProtobufRpcEngine$Invoker.invoke(ProtobufRpcEngine.java:232)
at com.sun.proxy.$Proxy111.getClusterMetrics(Unknown Source)
at org.apache.hadoop.yarn.api.impl.pb.client.ApplicationClientProtocolPBClientImpl.getClusterMetrics(ApplicationClientProtocolPBClientImpl.java:202)
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 org.apache.hadoop.io.retry.RetryInvocationHandler.invokeMethod(RetryInvocationHandler.java:187)
at org.apache.hadoop.io.retry.RetryInvocationHandler.invoke(RetryInvocationHandler.java:102)
at com.sun.proxy.$Proxy112.getClusterMetrics(Unknown Source)
at org.apache.hadoop.yarn.client.api.impl.YarnClientImpl.getYarnClusterMetrics(YarnClientImpl.java:461)
at org.apache.hadoop.mapred.ResourceMgrDelegate.getClusterMetrics(ResourceMgrDelegate.java:151)
at org.apache.hadoop.mapred.YARNRunner.getClusterMetrics(YARNRunner.java:179)
at org.apache.hadoop.mapreduce.Cluster.getClusterStatus(Cluster.java:246)
at org.apache.hadoop.mapred.JobClient$3.run(JobClient.java:719)
at org.apache.hadoop.mapred.JobClient$3.run(JobClient.java:717)
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:422)
at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1628)
at org.apache.hadoop.mapred.JobClient.getClusterStatus(JobClient.java:717)
at org.apache.solr.hadoop.MapReduceIndexerTool.run(MapReduceIndexerTool.java:645)
at org.apache.solr.hadoop.MapReduceIndexerTool.run(MapReduceIndexerTool.java:608)
at org.apache.hadoop.util.ToolRunner.run(ToolRunner.java:70)
at org.apache.solr.hadoop.MorphlineGoLiveMiniMRTest.test(MorphlineGoLiveMiniMRTest.java:401)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.io.IOException: Connection reset by peer
at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)
at sun.nio.ch.IOUtil.write(IOUtil.java:65)
at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:471)
at org.apache.hadoop.net.SocketOutputStream$Writer.performIO(SocketOutputStream.java:63)
at org.apache.hadoop.net.SocketIOWithTimeout.doIO(SocketIOWithTimeout.java:142)
at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:159)
at org.apache.hadoop.net.SocketOutputStream.write(SocketOutputStream.java:117)
at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82)
at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140)
at java.io.DataOutputStream.flush(DataOutputStream.java:123)
at org.apache.hadoop.ipc.Client$Connection$3.run(Client.java:1030)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
... 1 more
Build Log:
[...truncated 20063 lines...]
[junit4] Suite: org.apache.solr.hadoop.MorphlineBasicMiniMRTest
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/init-core-data-001
[junit4] 2> 0 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1398 WARN (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-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> 1477 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem No KeyProvider found.
[junit4] 2> 1503 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem fsLock is fair:true
[junit4] 2> 1591 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.c.C.deprecation hadoop.configured.node.mapping is deprecated. Instead, use net.topology.configured.node.mapping
[junit4] 2> 1591 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.DatanodeManager dfs.block.invalidate.limit=1000
[junit4] 2> 1591 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.DatanodeManager dfs.namenode.datanode.registration.ip-hostname-check=true
[junit4] 2> 1597 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager dfs.namenode.startup.delay.block.deletion.sec is set to 000:00:00:00.000
[junit4] 2> 1604 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager The block deletion will start around 2016 mai 11 22:04:38
[junit4] 2> 1607 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map BlocksMap
[junit4] 2> 1607 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 1609 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 2.0% max memory 455.5 MB = 9.1 MB
[junit4] 2> 1609 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^20 = 1048576 entries
[junit4] 2> 1634 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager dfs.block.access.token.enable=false
[junit4] 2> 1635 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager defaultReplication = 2
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxReplication = 512
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager minReplication = 1
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxReplicationStreams = 2
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager shouldCheckForEnoughRacks = false
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager replicationRecheckInterval = 3000
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager encryptDataTransfer = false
[junit4] 2> 1639 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxNumBlocksToLog = 1000
[junit4] 2> 1648 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem fsOwner = jenkins (auth:SIMPLE)
[junit4] 2> 1648 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem supergroup = supergroup
[junit4] 2> 1648 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem isPermissionEnabled = true
[junit4] 2> 1654 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem HA Enabled: false
[junit4] 2> 1658 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Append Enabled: true
[junit4] 2> 2053 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map INodeMap
[junit4] 2> 2053 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 2055 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 1.0% max memory 455.5 MB = 4.6 MB
[junit4] 2> 2055 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^19 = 524288 entries
[junit4] 2> 2055 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode Caching file names occuring more than 10 times
[junit4] 2> 2066 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map cachedBlocks
[junit4] 2> 2066 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 2066 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 0.25% max memory 455.5 MB = 1.1 MB
[junit4] 2> 2066 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^17 = 131072 entries
[junit4] 2> 2068 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.threshold-pct = 0.9990000128746033
[junit4] 2> 2068 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.min.datanodes = 0
[junit4] 2> 2068 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.extension = 0
[junit4] 2> 2070 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Retry cache on namenode is enabled
[junit4] 2> 2070 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Retry cache will use 0.03 of total heap and retry cache entry expiry time is 600000 millis
[junit4] 2> 2073 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map NameNodeRetryCache
[junit4] 2> 2074 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 2074 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 0.029999999329447746% max memory 455.5 MB = 139.9 KB
[junit4] 2> 2074 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^14 = 16384 entries
[junit4] 2> 2080 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf ACLs enabled? false
[junit4] 2> 2080 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf XAttrs enabled? true
[junit4] 2> 2080 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf Maximum size of an xattr: 16384
[junit4] 2> 2237 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSImage Allocated new BlockPoolId: BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 2266 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name1 has been successfully formatted.
[junit4] 2> 2270 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name2 has been successfully formatted.
[junit4] 2> 2509 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNStorageRetentionManager Going to retain 1 images with txid >= 0
[junit4] 2> 2511 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode createNameNode []
[junit4] 2> 2568 WARN (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 2670 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl Scheduled snapshot period at 10 second(s).
[junit4] 2> 2671 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl NameNode metrics system started
[junit4] 2> 2674 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode fs.defaultFS is hdfs://127.0.0.1:0
[junit4] 2> 2723 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.DFSUtil Starting Web-server for hdfs at: http://localhost:0
[junit4] 2> 2828 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
[junit4] 2> 2837 WARN (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 2883 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter)
[junit4] 2> 2885 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context hdfs
[junit4] 2> 2886 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context logs
[junit4] 2> 2886 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static
[junit4] 2> 2953 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter 'org.apache.hadoop.hdfs.web.AuthFilter' (class=org.apache.hadoop.hdfs.web.AuthFilter)
[junit4] 2> 2955 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 addJerseyResourcePackage: packageName=org.apache.hadoop.hdfs.server.namenode.web.resources;org.apache.hadoop.hdfs.web.resources, pathSpec=/webhdfs/v1/*
[junit4] 2> 2984 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Jetty bound to port 54814
[junit4] 2> 2984 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3036 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_54814_hdfs____.s76i9m/webapp
[junit4] 2> 3249 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 4443 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:54814
[junit4] 2> 4451 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem No KeyProvider found.
[junit4] 2> 4451 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem fsLock is fair:true
[junit4] 2> 4452 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.DatanodeManager dfs.block.invalidate.limit=1000
[junit4] 2> 4452 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.DatanodeManager dfs.namenode.datanode.registration.ip-hostname-check=true
[junit4] 2> 4452 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager dfs.namenode.startup.delay.block.deletion.sec is set to 000:00:00:00.000
[junit4] 2> 4452 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager The block deletion will start around 2016 mai 11 22:04:41
[junit4] 2> 4453 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map BlocksMap
[junit4] 2> 4453 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 4454 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 2.0% max memory 455.5 MB = 9.1 MB
[junit4] 2> 4454 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^20 = 1048576 entries
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager dfs.block.access.token.enable=false
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager defaultReplication = 2
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxReplication = 512
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager minReplication = 1
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxReplicationStreams = 2
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager shouldCheckForEnoughRacks = false
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager replicationRecheckInterval = 3000
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager encryptDataTransfer = false
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.b.BlockManager maxNumBlocksToLog = 1000
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem fsOwner = jenkins (auth:SIMPLE)
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem supergroup = supergroup
[junit4] 2> 4455 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem isPermissionEnabled = true
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem HA Enabled: false
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Append Enabled: true
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map INodeMap
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 1.0% max memory 455.5 MB = 4.6 MB
[junit4] 2> 4456 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^19 = 524288 entries
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode Caching file names occuring more than 10 times
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map cachedBlocks
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 0.25% max memory 455.5 MB = 1.1 MB
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^17 = 131072 entries
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.threshold-pct = 0.9990000128746033
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.min.datanodes = 0
[junit4] 2> 4457 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem dfs.namenode.safemode.extension = 0
[junit4] 2> 4458 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Retry cache on namenode is enabled
[junit4] 2> 4458 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Retry cache will use 0.03 of total heap and retry cache entry expiry time is 600000 millis
[junit4] 2> 4458 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet Computing capacity for map NameNodeRetryCache
[junit4] 2> 4458 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet VM type = 64-bit
[junit4] 2> 4459 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet 0.029999999329447746% max memory 455.5 MB = 139.9 KB
[junit4] 2> 4459 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.u.GSet capacity = 2^14 = 16384 entries
[junit4] 2> 4461 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf ACLs enabled? false
[junit4] 2> 4461 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf XAttrs enabled? true
[junit4] 2> 4461 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NNConf Maximum size of an xattr: 16384
[junit4] 2> 4480 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name1/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 4493 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name2/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 4498 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FileJournalManager Recovering unfinalized segments in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name1/current
[junit4] 2> 4498 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FileJournalManager Recovering unfinalized segments in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name2/current
[junit4] 2> 4499 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSImage No edit log streams selected.
[junit4] 2> 4524 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSImageFormatPBINode Loading 1 INodes.
[junit4] 2> 4534 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSImageFormatProtobuf Loaded FSImage in 0 seconds.
[junit4] 2> 4534 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSImage Loaded image for txid 0 from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/name1/current/fsimage_0000000000000000000
[junit4] 2> 4539 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Need to save fs image? false (staleImage=false, haEnabled=false, isRollingUpgrade=false)
[junit4] 2> 4540 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSEditLog Starting log segment at 1
[junit4] 2> 4577 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameCache initialized with 0 entries 0 lookups
[junit4] 2> 4578 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Finished loading FSImage in 116 msecs
[junit4] 2> 4934 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode RPC server is binding to localhost:0
[junit4] 2> 4946 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.i.CallQueueManager Using callQueue class java.util.concurrent.LinkedBlockingQueue
[junit4] 2> 4982 INFO (Socket Reader #1 for port 36483) [ ] o.a.h.i.Server Starting Socket Reader #1 for port 36483
[junit4] 2> 5031 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode Clients are to use localhost:36483 to access this namenode/service.
[junit4] 2> 5034 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Registered FSNamesystemState MBean
[junit4] 2> 5089 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Number of blocks under construction: 0
[junit4] 2> 5089 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Number of blocks under construction: 0
[junit4] 2> 5089 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem initializing replication queues
[junit4] 2> 5094 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.StateChange STATE* Leaving safe mode after 0 secs
[junit4] 2> 5094 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.StateChange STATE* Network topology has 0 racks and 0 datanodes
[junit4] 2> 5095 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.StateChange STATE* UnderReplicatedBlocks has 0 blocks
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.s.b.BlockManager Total number of blocks = 0
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.s.b.BlockManager Number of invalid blocks = 0
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.s.b.BlockManager Number of under-replicated blocks = 0
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.s.b.BlockManager Number of over-replicated blocks = 0
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.s.b.BlockManager Number of blocks being written = 0
[junit4] 2> 5102 INFO (Replication Queue Initializer) [ ] o.a.h.h.StateChange STATE* Replication Queue initialization scan for invalid, over- and under-replicated blocks completed in 3 msec
[junit4] 2> 5157 INFO (IPC Server Responder) [ ] o.a.h.i.Server IPC Server Responder: starting
[junit4] 2> 5186 INFO (IPC Server listener on 36483) [ ] o.a.h.i.Server IPC Server listener on 36483: starting
[junit4] 2> 5199 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.NameNode NameNode RPC up at: localhost/127.0.0.1:36483
[junit4] 2> 5199 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Starting services required for active state
[junit4] 2> 5224 INFO (CacheReplicationMonitor(298219226)) [ ] o.a.h.h.s.b.CacheReplicationMonitor Starting CacheReplicationMonitor with interval 30000 milliseconds
[junit4] 2> 5224 INFO (CacheReplicationMonitor(298219226)) [ ] o.a.h.h.s.b.CacheReplicationMonitor Rescanning after 1344544963 milliseconds
[junit4] 2> 5224 INFO (CacheReplicationMonitor(298219226)) [ ] o.a.h.h.s.b.CacheReplicationMonitor Scanned 0 directive(s) and 0 block(s) in 0 millisecond(s).
[junit4] 2> 5228 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.MiniDFSCluster Starting DataNode 0 with dfs.datanode.data.dir: [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1,[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2
[junit4] 2> 5333 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl DataNode metrics system started (again)
[junit4] 2> 5334 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Configured hostname is 127.0.0.1
[junit4] 2> 5340 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Starting DataNode with maxLockedMemory = 0
[junit4] 2> 5354 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Opened streaming server at /127.0.0.1:47579
[junit4] 2> 5357 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Balancing bandwith is 1048576 bytes/s
[junit4] 2> 5357 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Number threads for balancing is 5
[junit4] 2> 5361 WARN (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 5362 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter)
[junit4] 2> 5363 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context datanode
[junit4] 2> 5363 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context logs
[junit4] 2> 5363 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static
[junit4] 2> 5368 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 addJerseyResourcePackage: packageName=org.apache.hadoop.hdfs.server.datanode.web.resources;org.apache.hadoop.hdfs.web.resources, pathSpec=/webhdfs/v1/*
[junit4] 2> 5368 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Jetty bound to port 36712
[junit4] 2> 5368 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 5391 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_36712_datanode____piyyt8/webapp
[junit4] 2> 5569 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 6033 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36712
[junit4] 2> 6050 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode dnUserName = jenkins
[junit4] 2> 6050 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode supergroup = supergroup
[junit4] 2> 6077 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.i.CallQueueManager Using callQueue class java.util.concurrent.LinkedBlockingQueue
[junit4] 2> 6094 INFO (Socket Reader #1 for port 56172) [ ] o.a.h.i.Server Starting Socket Reader #1 for port 56172
[junit4] 2> 6109 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Opened IPC server at /127.0.0.1:56172
[junit4] 2> 6137 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Refresh request received for nameservices: null
[junit4] 2> 6140 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Starting BPOfferServices for nameservices: <default>
[junit4] 2> 6179 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Block pool <registering> (Datanode Uuid unassigned) service to localhost/127.0.0.1:36483 starting to offer service
[junit4] 2> 6193 INFO (IPC Server Responder) [ ] o.a.h.i.Server IPC Server Responder: starting
[junit4] 2> 6209 INFO (IPC Server listener on 56172) [ ] o.a.h.i.Server IPC Server listener on 56172: starting
[junit4] 2> 6296 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.MiniDFSCluster Starting DataNode 1 with dfs.datanode.data.dir: [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3,[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4
[junit4] 2> 6538 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl DataNode metrics system started (again)
[junit4] 2> 6538 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Configured hostname is 127.0.0.1
[junit4] 2> 6538 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Starting DataNode with maxLockedMemory = 0
[junit4] 2> 6538 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Opened streaming server at /127.0.0.1:55051
[junit4] 2> 6539 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Balancing bandwith is 1048576 bytes/s
[junit4] 2> 6539 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Number threads for balancing is 5
[junit4] 2> 6540 WARN (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 6541 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added global filter 'safety' (class=org.apache.hadoop.http.HttpServer2$QuotingInputFilter)
[junit4] 2> 6541 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context datanode
[junit4] 2> 6541 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context static
[junit4] 2> 6541 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Added filter static_user_filter (class=org.apache.hadoop.http.lib.StaticUserWebFilter$StaticUserFilter) to context logs
[junit4] 2> 6542 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 addJerseyResourcePackage: packageName=org.apache.hadoop.hdfs.server.datanode.web.resources;org.apache.hadoop.hdfs.web.resources, pathSpec=/webhdfs/v1/*
[junit4] 2> 6542 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.HttpServer2 Jetty bound to port 40152
[junit4] 2> 6542 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 6584 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_40152_datanode____.dfrn9j/webapp
[junit4] 2> 6719 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet
[junit4] 2> 7113 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage DataNode version: -56 and NameNode layout version: -60
[junit4] 2> 7179 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 7181 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1 is not formatted for BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7181 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7193 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 7195 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2 is not formatted for BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7195 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7209 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40152
[junit4] 2> 7209 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode dnUserName = jenkins
[junit4] 2> 7209 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode supergroup = supergroup
[junit4] 2> 7210 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.i.CallQueueManager Using callQueue class java.util.concurrent.LinkedBlockingQueue
[junit4] 2> 7220 INFO (Socket Reader #1 for port 39482) [ ] o.a.h.i.Server Starting Socket Reader #1 for port 39482
[junit4] 2> 7242 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Opened IPC server at /127.0.0.1:39482
[junit4] 2> 7261 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Refresh request received for nameservices: null
[junit4] 2> 7262 INFO (SUITE-MorphlineBasicMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.d.DataNode Starting BPOfferServices for nameservices: <default>
[junit4] 2> 7268 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Block pool <registering> (Datanode Uuid unassigned) service to localhost/127.0.0.1:36483 starting to offer service
[junit4] 2> 7328 INFO (IPC Server Responder) [ ] o.a.h.i.Server IPC Server Responder: starting
[junit4] 2> 7332 INFO (IPC Server listener on 39482) [ ] o.a.h.i.Server IPC Server listener on 39482: starting
[junit4] 2> 7456 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage DataNode version: -56 and NameNode layout version: -60
[junit4] 2> 7467 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 7467 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3 is not formatted for BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7467 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7475 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Lock on /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/in_use.lock acquired by nodename 32128@lucene1-us-west
[junit4] 2> 7476 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4 is not formatted for BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7476 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7507 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Analyzing storage directories for bpid BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7507 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Locking is disabled
[junit4] 2> 7509 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/current/BP-2140923535-10.41.0.5-1462968279112 is not formatted.
[junit4] 2> 7509 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7509 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting block pool BP-2140923535-10.41.0.5-1462968279112 directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/current/BP-2140923535-10.41.0.5-1462968279112/current
[junit4] 2> 7512 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Locking is disabled
[junit4] 2> 7512 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/current/BP-2140923535-10.41.0.5-1462968279112 is not formatted.
[junit4] 2> 7512 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7513 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting block pool BP-2140923535-10.41.0.5-1462968279112 directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/current/BP-2140923535-10.41.0.5-1462968279112/current
[junit4] 2> 7515 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Restored 0 block files from trash.
[junit4] 2> 7516 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Restored 0 block files from trash.
[junit4] 2> 7519 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Setting up storage: nsid=772084273;bpid=BP-2140923535-10.41.0.5-1462968279112;lv=-56;nsInfo=lv=-60;cid=testClusterID;nsid=772084273;c=0;bpid=BP-2140923535-10.41.0.5-1462968279112;dnuuid=null
[junit4] 2> 7529 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Generated and persisted new Datanode UUID 186886df-77e3-430e-a393-cde161307efc
[junit4] 2> 7661 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Analyzing storage directories for bpid BP-2140923535-10.41.0.5-1462968279112
[junit4] 2> 7661 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Locking is disabled
[junit4] 2> 7662 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/current/BP-2140923535-10.41.0.5-1462968279112 is not formatted.
[junit4] 2> 7662 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7662 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting block pool BP-2140923535-10.41.0.5-1462968279112 directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/current/BP-2140923535-10.41.0.5-1462968279112/current
[junit4] 2> 7664 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added new volume: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/current
[junit4] 2> 7666 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added volume - /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/current, StorageType: DISK
[junit4] 2> 7667 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Locking is disabled
[junit4] 2> 7669 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Storage directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/current/BP-2140923535-10.41.0.5-1462968279112 is not formatted.
[junit4] 2> 7669 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting ...
[junit4] 2> 7669 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Formatting block pool BP-2140923535-10.41.0.5-1462968279112 directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/current/BP-2140923535-10.41.0.5-1462968279112/current
[junit4] 2> 7670 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added new volume: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/current
[junit4] 2> 7670 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data1/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added volume - /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data2/current, StorageType: DISK
[junit4] 2> 7671 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Restored 0 block files from trash.
[junit4] 2> 7671 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.c.Storage Restored 0 block files from trash.
[junit4] 2> 7677 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Setting up storage: nsid=772084273;bpid=BP-2140923535-10.41.0.5-1462968279112;lv=-56;nsInfo=lv=-60;cid=testClusterID;nsid=772084273;c=0;bpid=BP-2140923535-10.41.0.5-1462968279112;dnuuid=null
[junit4] 2> 7680 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.DataNode Generated and persisted new Datanode UUID 872254a7-feb3-48ee-8eaa-c7d614e64f26
[junit4] 2> 7681 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added new volume: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/current
[junit4] 2> 7700 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added volume - /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/current, StorageType: DISK
[junit4] 2> 7700 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added new volume: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/current
[junit4] 2> 7700 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/, [DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/]] heartbeating to localhost/127.0.0.1:36483) [ ] o.a.h.h.s.d.f.i.FsDatasetImpl Added volume - /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data4/current, StorageType: DISK
[junit4] 2> 7760 INFO (DataNode: [[[DISK]file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-map-reduce/test/J0/temp/solr.hadoop.MorphlineBasicMiniMRTest_898818BB2E65B20E-001/tempDir-002/hdfs/build/dfs/data/data3/,
[...truncated too long message...]
079 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.h.s.n.FSNamesystem Stopping services started for standby state
[junit4] 2> 67082 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 67183 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl Stopping JobHistoryServer metrics system...
[junit4] 2> 67185 ERROR (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.l.MethodMetric Error invoking method getBlocksTotal
[junit4] 2> java.lang.reflect.InvocationTargetException
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric$2.snapshot(MethodMetric.java:111)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MethodMetric.snapshot(MethodMetric.java:144)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsRegistry.snapshot(MetricsRegistry.java:387)
[junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212)
[junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592)
[junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72)
[junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822)
[junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720)
[junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699)
[junit4] 2> at org.apache.solr.hadoop.MorphlineGoLiveMiniMRTest.teardownClass(MorphlineGoLiveMiniMRTest.java:235)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: java.lang.NullPointerException
[junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198)
[junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291)
[junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223)
[junit4] 2> ... 53 more
[junit4] 2> 67195 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl JobHistoryServer metrics system stopped.
[junit4] 2> 67195 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-worker) [ ] o.a.h.m.i.MetricsSystemImpl JobHistoryServer metrics system shutdown complete.
[junit4] 2> 67198 INFO (SUITE-MorphlineGoLiveMiniMRTest-seed#[898818BB2E65B20E]-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/solr/build/contrib/solr-map-reduce/test/J1/temp/solr.hadoop.MorphlineGoLiveMiniMRTest_898818BB2E65B20E-001
[junit4] 2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=13817, maxDocsPerChunk=6, blockSize=5), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=13817, blockSize=5)), sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ja-JP-u-ca-japanese-x-lvariant-JP, timezone=America/Whitehorse
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=8,free=271649208,total=390594560
[junit4] 2> NOTE: All tests run in this JVM: [MorphlineGoLiveMiniMRTest]
[junit4] Completed [7/7 (2!)] on J1 in 67.83s, 2 tests, 1 error <<< FAILURES!
[...truncated 13 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:253: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:464: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 7 suites, 35 tests, 1 suite-level error, 2 errors, 1 ignored [seed: 898818BB2E65B20E]
Total time: 246 minutes 53 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-NightlyTests-6.x - Build # 61 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/61/
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 5 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 5 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([5B2A608B99363170]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11354 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/init-core-data-001
[junit4] 2> 0 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestReplicateAfterCoreReload
[junit4] 2> 6 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1
[junit4] 2> 106 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.u.log Logging initialized @2359ms
[junit4] 2> 335 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 369 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@348b91ad{/solr,null,AVAILABLE}
[junit4] 2> 381 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.ServerConnector Started ServerConnector@629ba444{HTTP/1.1,[http/1.1]}{127.0.0.1:44344}
[junit4] 2> 381 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server Started @2636ms
[junit4] 2> 381 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=44344}
[junit4] 2> 391 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 421 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001'
[junit4] 2> 459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 459 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 469 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/solr.xml
[junit4] 2> 648 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/.
[junit4] 2> 713 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer New CoreContainer 863773104
[junit4] 2> 714 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001]
[junit4] 2> 714 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/lib
[junit4] 2> 748 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 950 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 960 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 961 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 968 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 968 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1036 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/.
[junit4] 2> 1042 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 1043 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1
[junit4] 2> 1043 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1059 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1'
[junit4] 2> 1059 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1059 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1061 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0
[junit4] 2> 1063 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1087 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1101 WARN (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 1102 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 1219 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1230 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1279 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1282 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1'
[junit4] 2> 1283 INFO (coreLoadExecutor-6-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1
[junit4] 2> 1313 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1322 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1322 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1339 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data
[junit4] 2> 1339 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 1339 WARN (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1350 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index
[junit4] 2> 1352 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1389 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46cd6c8a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52a7f64b),segFN=segments_1,generation=1}
[junit4] 2> 1390 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1390 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/
[junit4] 2> 1540 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1611 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1614 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1615 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1616 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1793 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1794 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 1794 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1804 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1804 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1805 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4844190444105039]
[junit4] 2> 1807 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@46cd6c8a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@52a7f64b),segFN=segments_1,generation=1}
[junit4] 2> 1807 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1824 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a5ec5cb[collection1] main]
[junit4] 2> 1835 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf
[junit4] 2> 1835 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf}
[junit4] 2> 1852 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf
[junit4] 2> 1852 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1866 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1867 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1885 INFO (searcherExecutor-7-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a5ec5cb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1886 INFO (coreLoadExecutor-6-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2085 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1
[junit4] 2> 2095 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2097 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56977b6b{/solr,null,AVAILABLE}
[junit4] 2> 2098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.ServerConnector Started ServerConnector@748e6f74{HTTP/1.1,[http/1.1]}{127.0.0.1:57890}
[junit4] 2> 2098 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server Started @4353ms
[junit4] 2> 2099 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1/data, hostContext=/solr, hostPort=57890}
[junit4] 2> 2100 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 2101 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002'
[junit4] 2> 2101 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2101 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2101 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/solr.xml
[junit4] 2> 2114 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/.
[junit4] 2> 2115 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer New CoreContainer 868693797
[junit4] 2> 2115 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002]
[junit4] 2> 2115 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/lib
[junit4] 2> 2115 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2117 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2117 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2117 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2117 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2117 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2118 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/.
[junit4] 2> 2120 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 2120 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1
[junit4] 2> 2120 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 2127 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1'
[junit4] 2> 2127 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0
[junit4] 2> 2127 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2127 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2128 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2138 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2144 WARN (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2145 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 2157 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2161 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2165 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2167 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1'
[junit4] 2> 2167 INFO (coreLoadExecutor-16-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1
[junit4] 2> 2167 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2168 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/]
[junit4] 2> 2168 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 2168 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data
[junit4] 2> 2168 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 2169 WARN (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2169 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index
[junit4] 2> 2169 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2170 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53afb380 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70095801),segFN=segments_1,generation=1}
[junit4] 2> 2170 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2175 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2178 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2180 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2180 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2182 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2182 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/
[junit4] 2> 2201 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2201 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 2201 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2201 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2201 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2202 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4844190444105039]
[junit4] 2> 2202 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@53afb380 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70095801),segFN=segments_1,generation=1}
[junit4] 2> 2202 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2202 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ef118d[collection1] main]
[junit4] 2> 2203 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1/conf
[junit4] 2> 2203 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1/conf}
[junit4] 2> 2203 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/collection1/conf
[junit4] 2> 2203 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2235 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 2235 INFO (coreLoadExecutor-16-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2236 INFO (searcherExecutor-17-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ef118d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2635 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@748e6f74{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2636 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=868693797
[junit4] 2> 2637 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@28c5cb73
[junit4] 2> 2639 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2640 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 2640 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2640 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 2647 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 2650 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2651 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data;done=false>>]
[junit4] 2> 2651 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2652 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data
[junit4] 2> 2652 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index;done=false>>]
[junit4] 2> 2652 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2652 INFO (coreCloseExecutor-22-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-002/./collection1/data/index
[junit4] 2> 2657 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@56977b6b{/solr,null,UNAVAILABLE}
[junit4] 2> 2673 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@629ba444{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2674 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=863773104
[junit4] 2> 2674 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@17e182be
[junit4] 2> 2675 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2675 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 2675 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2675 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data;done=false>>]
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index;done=false>>]
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 2676 INFO (coreCloseExecutor-24-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index
[junit4] 2> 2684 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@348b91ad{/solr,null,UNAVAILABLE}
[junit4] 2> 2781 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 2787 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d8f4cf4{/solr,null,AVAILABLE}
[junit4] 2> 2788 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2ed25cb7{HTTP/1.1,[http/1.1]}{127.0.0.1:36476}
[junit4] 2> 2788 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.e.j.s.Server Started @5043ms
[junit4] 2> 2788 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/data, hostContext=/solr, hostPort=36476}
[junit4] 2> 2789 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93
[junit4] 2> 2789 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001'
[junit4] 2> 2789 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2793 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2793 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/solr.xml
[junit4] 2> 2802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/.
[junit4] 2> 2802 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer New CoreContainer 1628474489
[junit4] 2> 2803 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001]
[junit4] 2> 2803 WARN (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/lib
[junit4] 2> 2804 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 2805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 2805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 2805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 2805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 2805 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 2806 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/.
[junit4] 2> 2808 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, loadOnStartup=true, schema=schema.xml, configSetProperties=configsetprops.json, transient=false, dataDir=data/}
[junit4] 2> 2808 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1
[junit4] 2> 2808 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 2808 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0
[junit4] 2> 2808 INFO (TEST-TestReplicationHandler.doTestReplicateAfterCoreReload-seed#[5B2A608B99363170]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 2812 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1'
[junit4] 2> 2812 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2812 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 2823 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 2834 WARN (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 2835 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 2888 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2900 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2904 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 2906 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1'
[junit4] 2> 2906 INFO (coreLoadExecutor-31-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1
[junit4] 2> 2907 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2911 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/]
[junit4] 2> 2911 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 2912 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data
[junit4] 2> 2912 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 2912 WARN (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 2912 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/index
[junit4] 2> 2913 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=44, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 2913 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bb44a6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16aa2bff),segFN=segments_1,generation=1}
[junit4] 2> 2913 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2915 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/./collection1/data/
[junit4] 2> 2916 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 2917 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2918 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2918 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2919 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,lazy,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,defaults,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2921 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4844190444105039]
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@bb44a6 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@16aa2bff),segFN=segments_1,generation=1}
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@656a20e7[collection1] main]
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf}
[junit4] 2> 2922 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-001/collection1/conf
[junit4] 2> 2923 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 2923 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 2923 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2923 INFO (coreLoadExecutor-31-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 2953 INFO (searcherExecutor-32-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@656a20e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3420 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 3422 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 53
[junit4] 2> 3582 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 104
[junit4] 2> 3587 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 3591 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 3594 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 3597 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 3600 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 3603 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 3607 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 3610 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 3613 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 3616 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 3619 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 3622 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 3625 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 3628 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 3632 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 3638 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 3642 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 3646 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 3649 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 3653 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 1
[junit4] 2> 3657 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 3660 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 3663 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 3666 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 3669 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 3672 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 3675 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 3678 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 3680 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 3685 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 2
[junit4] 2> 3688 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 3691 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 3694 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 3696 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 3699 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 3702 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 3705 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 3708 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 3710 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 3713 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 3716 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 3719 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 3722 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 3725 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 3727 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 3730 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 3733 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 3735 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 3738 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 3742 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 3745 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 3748 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 3751 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 3756 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 3759 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 3762 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 3766 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 3769 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 3772 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 3775 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 3783 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 5
[junit4] 2> 3786 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 3789 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 3796 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 3799 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 3801 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 3804 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 3807 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 3810 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 3813 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 3816 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 3819 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 3822 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 3824 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 3830 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 3833 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 3836 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 3839 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 3842 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 3845 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 3848 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 3850 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 3854 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 3858 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 3861 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 3864 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 3866 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 3869 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 3871 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 3874 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 3877 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 3879 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 3882 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 3885 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 3888 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 3890 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 3893 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 3898 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 3902 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 3905 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 3908 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 3910 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 3913 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 3915 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 3918 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 3921 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 3923 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 3926 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 3928 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 3931 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 3939 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 3942 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 3945 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 3948 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 3950 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 3953 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 3956 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 3959 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 3962 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 3964 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 3968 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 3971 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 3973 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 3976 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 3979 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 3984 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 4038 INFO (qtp576440226-52) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 52
[junit4] 2> 4042 INFO (qtp576440226-58) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 4045 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 4047 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 4050 INFO (qtp576440226-56) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 4053 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 4055 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 4058 INFO (qtp576440226-57) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 4061 INFO (qtp576440226-55) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 4063 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 4066 INFO (qtp576440226-59) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 4068 INFO (q
[...truncated too long message...]
s=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 582550 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 582550 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 582550 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 582550 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data;done=false>>]
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data/index;done=false>>]
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory, CoreContainer#isShutdown=true
[junit4] 2> 582551 INFO (coreCloseExecutor-838-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_5B2A608B99363170-001/solr-instance-029/./collection1/data/index
[junit4] 2> 582551 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[5B2A608B99363170]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4b7f5d13{/solr,null,UNAVAILABLE}
[junit4] 2> 582552 INFO (SUITE-TestReplicationHandler-seed#[5B2A608B99363170]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> ObjectTracker found 5 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:388)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:276)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:388)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:276)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:388)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:276)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:388)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:276)
[junit4] 2>
[junit4] 2> org.apache.solr.common.util.ObjectReleaseTracker$ObjectTrackerException
[junit4] 2> at org.apache.solr.common.util.ObjectReleaseTracker.track(ObjectReleaseTracker.java:42)
[junit4] 2> at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:347)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367)
[junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:251)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:388)
[junit4] 2> at org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:276)
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {name=FST50, id=FST50, newname=FST50}, docValues:{}, maxPointsInLeafNode=1172, maxMBSortInHeap=5.083603170288546, sim=ClassicSimilarity, locale=bg, timezone=Europe/Malta
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=141279432,total=322437120
[junit4] 2> NOTE: All tests run in this JVM: [TestReplicationHandler]
[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=TestReplicationHandler -Dtests.seed=5B2A608B99363170 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=bg -Dtests.timezone=Europe/Malta -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | TestReplicationHandler (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 5 object(s) that were not released!!! [NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory, NRTCachingDirectory]
[junit4] > at __randomizedtesting.SeedInfo.seed([5B2A608B99363170]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:256)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [126/598 (1!)] on J0 in 584.04s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 1532 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:747: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1457: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1014: There were test failures: 598 suites (10 ignored), 2419 tests, 1 suite-level error, 32 ignored (17 assumptions) [seed: 5B2A608B99363170]
Total time: 219 minutes 2 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any