You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/08/22 08:00:16 UTC
[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-10) - Build # 22721 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22721/
Java: 64bit/jdk-10 -XX:+UseCompressedOops -XX:+UseSerialGC
4 tests failed.
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 15217 lines...]
[junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/init-core-data-001
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 3182419 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 3182424 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 3182425 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3182427 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_localdomain_36555_hdfs____xn0uuk/webapp
[junit4] 2> 3182491 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:36555
[junit4] 2> 3182563 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 3182564 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3182567 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_36729_datanode____.gstf6m/webapp
[junit4] 2> 3182631 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36729
[junit4] 2> 3182672 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 3182673 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3182676 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_42469_datanode____2574m8/webapp
[junit4] 2> 3182749 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:42469
[junit4] 2> 3182759 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 3182766 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d8a8928976a0: from storage DS-d1d0eacb-fc67-4635-8af9-65f589db42f8 node DatanodeRegistration(127.0.0.1:41513, datanodeUuid=f40c0208-babe-47bd-9c0b-29893c1c88a2, infoPort=44791, infoSecurePort=0, ipcPort=42035, storageInfo=lv=-56;cid=testClusterID;nsid=2097955472;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 3182766 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d8a8928976a0: from storage DS-0fb944eb-0822-43af-b801-40da2658460d node DatanodeRegistration(127.0.0.1:41513, datanodeUuid=f40c0208-babe-47bd-9c0b-29893c1c88a2, infoPort=44791, infoSecurePort=0, ipcPort=42035, storageInfo=lv=-56;cid=testClusterID;nsid=2097955472;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 3182837 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 3182839 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d8a89704dfe2: from storage DS-3aabe042-8eb3-4066-99ce-8df3b4069e54 node DatanodeRegistration(127.0.0.1:43139, datanodeUuid=3b94b864-8788-4e8f-8fa7-fc8c065ff1ea, infoPort=45361, infoSecurePort=0, ipcPort=45609, storageInfo=lv=-56;cid=testClusterID;nsid=2097955472;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 3182839 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d8a89704dfe2: from storage DS-4286793b-fbfd-4d8b-b197-564084676bb9 node DatanodeRegistration(127.0.0.1:43139, datanodeUuid=3b94b864-8788-4e8f-8fa7-fc8c065ff1ea, infoPort=45361, infoSecurePort=0, ipcPort=45609, storageInfo=lv=-56;cid=testClusterID;nsid=2097955472;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 3182909 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 2> 3182910 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|FINALIZED], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> 3182919 INFO (IPC Server handler 1 on 38375) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:41513 127.0.0.1:43139
[junit4] 2> 3182941 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> 3182941 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741826_1002 size 4
[junit4] 2> 3182945 INFO (IPC Server handler 8 on 38375) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741826_1002 127.0.0.1:41513 127.0.0.1:43139
[junit4] 1> Working on pass [0] contains [false]
[junit4] 2> 3183017 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW]]} size 0
[junit4] 2> 3183017 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW]]} size 0
[junit4] 1> Working on pass [1] contains [false]
[junit4] 2> 3185254 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 2> 3185262 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741828_1004 size 2171949
[junit4] 2> 3185538 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@4f22e143) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:43139 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 1> Working on pass [2] contains [false]
[junit4] 2> 3186482 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 2> 3186484 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 1> Working on pass [3] contains [false]
[junit4] 2> 3186817 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> 3186818 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|FINALIZED], ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 2> 3188539 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@4f22e143) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:41513 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 1> Working on pass [4] contains [false]
[junit4] 2> 3188916 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> 3188916 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 1> Working on pass [5] contains [false]
[junit4] 2> 3189926 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 2> 3189927 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741832_1008 size 3693295
[junit4] 1> Working on pass [6] contains [false]
[junit4] 2> 3191818 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 2> 3191818 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|FINALIZED]]} size 0
[junit4] 1> Working on pass [7] contains [false]
[junit4] 2> 3192244 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 2> 3192244 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 1> Working on pass [8] contains [false]
[junit4] 2> 3193377 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> 3193377 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 1> Working on pass [9] contains [false]
[junit4] 2> 3193422 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 2> 3193422 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-4286793b-fbfd-4d8b-b197-564084676bb9:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW]]} size 0
[junit4] 2> 3194190 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:43139 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0fb944eb-0822-43af-b801-40da2658460d:NORMAL:127.0.0.1:41513|RBW], ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW]]} size 0
[junit4] 2> 3194190 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41513 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-3aabe042-8eb3-4066-99ce-8df3b4069e54:NORMAL:127.0.0.1:43139|RBW], ReplicaUC[[DISK]DS-d1d0eacb-fc67-4635-8af9-65f589db42f8:NORMAL:127.0.0.1:41513|FINALIZED]]} size 0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=FA7B62D62CF28EF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ckb-IQ -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.05s J0 | HdfsDirectoryTest.testEOF <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
[junit4] > at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 3194193 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 3194216 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 3194317 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 3194317 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-482027620-88.99.242.108-1534924015486 (Datanode Uuid 3b94b864-8788-4e8f-8fa7-fc8c065ff1ea) service to localhost.localdomain/127.0.0.1:38375
[junit4] 2> 3194318 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 3194334 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 3194434 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 3194435 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:38375) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-482027620-88.99.242.108-1534924015486 (Datanode Uuid f40c0208-babe-47bd-9c0b-29893c1c88a2) service to localhost.localdomain/127.0.0.1:38375
[junit4] 2> 3194442 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001
[junit4] 2> Aug 22, 2018 7:47:07 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 66 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=683, maxMBSortInHeap=6.551176223584393, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7d7adfc0), locale=ckb-IQ, timezone=America/Sao_Paulo
[junit4] 2> NOTE: Linux 4.15.0-32-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=2,free=219472096,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [NodeMarkersRegistrationTest, RecoveryZkTest, TestMiniSolrCloudClusterSSL, TestNumericRangeQuery64, TestRequestStatusCollectionAPI, SchemaVersionSpecificBehaviorTest, AtomicUpdatesTest, TestLegacyFieldCache, DocumentBuilderTest, TestStandardQParsers, SolrGangliaReporterTest, HdfsTlogReplayBufferedWhileIndexingTest, TestClusterStateProvider, TemplateUpdateProcessorTest, TriggerCooldownIntegrationTest, AutoScalingHandlerTest, SearchHandlerTest, OrderedExecutorTest, TestReloadDeadlock, TestEmbeddedSolrServerConstructors, MoveReplicaHDFSTest, TestCloudPseudoReturnFields, LeaderVoteWaitTimeoutTest, PolyFieldTest, TestStreamBody, NodeAddedTriggerTest, TestUniqueKeyFieldResource, DistributedFacetExistsSmallTest, ConcurrentCreateRoutedAliasTest, HdfsDirectoryFactoryTest, SampleTest, TestRandomDVFaceting, TestNestedDocsSort, TestAnalyzeInfixSuggestions, CurrencyFieldTypeTest, TestUninvertingReader, CoreSorterTest, TestLRUCache, TestRestoreCore, IgnoreCommitOptimizeUpdateProcessorFactoryTest, CollectionPropsTest, TestGroupingSearch, DistributedTermsComponentTest, RollingRestartTest, TestMissingGroups, PrimitiveFieldTypeTest, TestSolr4Spatial2, TestUnifiedSolrHighlighter, TestLeaderElectionZkExpiry, TestMergePolicyConfig, SolrShardReporterTest, BinaryUpdateRequestHandlerTest, TokenizerChainTest, OverriddenZkACLAndCredentialsProvidersTest, SearchRateTriggerTest, TestAtomicUpdateErrorCases, TestObjectReleaseTracker, TestSurroundQueryParser, RulesTest, SolrPluginUtilsTest, SmileWriterTest, TestInitParams, TestPivotHelperCode, BigEndianAscendingWordSerializerTest, TestHighFrequencyDictionaryFactory, TestUtils, CustomCollectionTest, TestCopyFieldCollectionResource, TestCloudConsistency, SolrCoreTest, TestReplicationHandler, TestXIncludeConfig, BlockDirectoryTest, SpellCheckCollatorWithCollapseTest, TestFaceting, TestPullReplica, CdcrReplicationHandlerTest, TestSimpleQParserPlugin, TestSolrCLIRunExample, OverseerRolesTest, ConnectionReuseTest, StatsComponentTest, SolrRequestParserTest, CursorPagingTest, TestSimpleTextCodec, TestTolerantUpdateProcessorCloud, CircularListTest, EchoParamsTest, DistributedUpdateProcessorTest, TestDynamicURP, DistribDocExpirationUpdateProcessorTest, BooleanFieldTest, QueryElevationComponentTest, SecurityConfHandlerTest, CreateCollectionCleanupTest, TimeRoutedAliasUpdateProcessorTest, SearchHandlerTest, SimpleFacetsTest, TestExecutePlanAction, PeerSyncWithLeaderTest, TestRawResponseWriter, DistributedFacetPivotSmallAdvancedTest, TestExactStatsCache, TestManagedResourceStorage, SortByFunctionTest, V2ApiIntegrationTest, LoggingHandlerTest, TestQuerySenderNoQuery, TestBM25SimilarityFactory, TestLuceneMatchVersion, CoreAdminRequestStatusTest, TestRTGBase, SortSpecParsingTest, HdfsNNFailoverTest, DeleteShardTest, TestStressInPlaceUpdates, TestSSLRandomization, TestIndexSearcher, StressHdfsTest, DistanceUnitsTest, MergeStrategyTest, CoreAdminCreateDiscoverTest, ZkCLITest, TestPerFieldSimilarityWithDefaultOverride, UUIDFieldTest, TestFieldCacheSortRandom, TestSolrQueryResponse, CdcrBidirectionalTest, ClassificationUpdateProcessorTest, HighlighterMaxOffsetTest, DeleteLastCustomShardedReplicaTest, UUIDUpdateProcessorFallbackTest, TestManagedSchemaAPI, DistributedMLTComponentTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, ChaosMonkeySafeLeaderWithPullReplicasTest, ClusterStateTest, CollectionsAPISolrJTest, FullSolrCloudDistribCmdsTest, HealthCheckHandlerTest, HttpPartitionOnCommitTest, MetricsHistoryIntegrationTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, NodeMutatorTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, OverseerTest, PeerSyncReplicationTest, SaslZkACLProviderTest, ShardRoutingTest, TestAuthenticationFramework, TestCloudJSONFacetJoinDomain, TestCloudJSONFacetSKG, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDistribDocBasedVersion, TestHashPartitioner, TestLockTree, TestRandomRequestDistribution, TestRequestForwarding, TestShortCircuitedRequests, TestSizeLimitedDistributedMap, TestSkipOverseerOperations, TestSolrCloudWithDelegationTokens, TestSolrCloudWithKerberosAlt, TestSolrCloudWithSecureImpersonation, TestTlogReplica, TestZkChroot, TriLevelCompositeIdRoutingTest, UnloadDistributedZkTest, ZkNodePropsTest, ZkShardTermsTest, ZkSolrClientTest, AssignTest, CollectionReloadTest, CollectionsAPIAsyncDistributedZkTest, ShardSplitTest, CdcrVersionReplicationTest, CdcrWithNodesRestartsTest, DirectSolrSpellCheckerTest, IndexBasedSpellCheckerTest, TestSuggestSpellingConverter, WordBreakSolrSpellCheckerTest, SuggesterTSTTest, SuggesterWFSTTest, TestFreeTextSuggestions, HdfsDirectoryTest]
[junit4] Completed [806/830 (1!)] on J0 in 22.04s, 4 tests, 1 error <<< FAILURES!
[...truncated 42290 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/22721/consoleText
[repro] Revision: 5eab1c3c688a0d8db650c657567f197fb3dcf181
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] Repro line: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=FA7B62D62CF28EF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ckb-IQ -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] HdfsDirectoryTest
[repro] ant compile-test
[...truncated 3396 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.HdfsDirectoryTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=FA7B62D62CF28EF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ckb-IQ -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 32 lines...]
[junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
[junit4] 2> 2102 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/init-core-data-001
[junit4] 2> 2167 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2193 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.e.j.u.log Logging initialized @٢٢١٧ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2205 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2742 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-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> 3420 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 3683 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Logging to org.apache.logging.slf4j.Log4jLogger@60e6286d via org.mortbay.log.Slf4jLog
[junit4] 2> 3698 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 3858 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3885 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_localdomain_33477_hdfs____.9bwacy/webapp
[junit4] 2> 4011 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:33477
[junit4] 2> 4383 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4386 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 4392 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_36449_datanode____xqqqfx/webapp
[junit4] 2> 4482 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:36449
[junit4] 2> 4497 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] i.n.u.i.PlatformDependent Your platform does not provide complete low-level API for accessing direct buffers reliably. Unless explicitly requested, heap buffer will always be preferred to avoid potential system unstability.
[junit4] 2> 4687 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4689 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 4701 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_40471_datanode____.ljiltz/webapp
[junit4] 2> 4778 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40471
[junit4] 2> 5316 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:40825) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 5316 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:40825) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 5385 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d912f80605ab: from storage DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4 node DatanodeRegistration(127.0.0.1:39965, datanodeUuid=38471165-7440-48ef-aa0f-72d2876258dd, infoPort=41303, infoSecurePort=0, ipcPort=46011, storageInfo=lv=-56;cid=testClusterID;nsid=787350370;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs
[junit4] 2> 5385 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d912f80606ae: from storage DS-dcc491f6-b624-45d8-908c-25c5d43538c7 node DatanodeRegistration(127.0.0.1:38343, datanodeUuid=e62c8169-5e9d-4d84-ac49-1b28f5042ae4, infoPort=35581, infoSecurePort=0, ipcPort=36611, storageInfo=lv=-56;cid=testClusterID;nsid=787350370;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 5385 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d912f80605ab: from storage DS-7ac2e126-6cca-4231-92db-e47795a03d28 node DatanodeRegistration(127.0.0.1:39965, datanodeUuid=38471165-7440-48ef-aa0f-72d2876258dd, infoPort=41303, infoSecurePort=0, ipcPort=46011, storageInfo=lv=-56;cid=testClusterID;nsid=787350370;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 5386 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d912f80606ae: from storage DS-6fc75186-30fc-47c4-912f-d1ff03447c2a node DatanodeRegistration(127.0.0.1:38343, datanodeUuid=e62c8169-5e9d-4d84-ac49-1b28f5042ae4, infoPort=35581, infoSecurePort=0, ipcPort=36611, storageInfo=lv=-56;cid=testClusterID;nsid=787350370;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 5538 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWritingAndReadingAFile
[junit4] 2> 5766 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW]]} size 4
[junit4] 2> 5767 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741825_1001 size 4
[junit4] 2> 6213 INFO (IPC Server handler 3 on 40825) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:39965 127.0.0.1:38343
[junit4] 2> 6217 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWritingAndReadingAFile
[junit4] OK 0.72s J2 | HdfsDirectoryTest.testWritingAndReadingAFile
[junit4] 2> 6239 INFO (TEST-HdfsDirectoryTest.testRename-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRename
[junit4] 2> 6281 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW]]} size 0
[junit4] 2> 6289 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 6385 INFO (IPC Server handler 4 on 40825) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741826_1002 127.0.0.1:39965 127.0.0.1:38343
[junit4] 2> 6387 INFO (TEST-HdfsDirectoryTest.testRename-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRename
[junit4] OK 0.16s J2 | HdfsDirectoryTest.testRename
[junit4] 2> 6398 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
[junit4] 1> Working on pass [0] contains [false]
[junit4] 2> 6588 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW]]} size 0
[junit4] 2> 6590 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 2> 7283 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@faaa33a) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:38343 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 2> 10283 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@faaa33a) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:39965 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 1> Working on pass [1] contains [false]
[junit4] 2> 10767 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW]]} size 0
[junit4] 2> 10767 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 1> Working on pass [2] contains [false]
[junit4] 2> 13639 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 2> 13639 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [3] contains [false]
[junit4] 2> 14616 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW]]} size 0
[junit4] 2> 14617 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [4] contains [false]
[junit4] 2> 19305 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 2> 19306 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 1> Working on pass [5] contains [false]
[junit4] 2> 20578 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 2> 20579 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [6] contains [false]
[junit4] 2> 23449 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 23449 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [7] contains [false]
[junit4] 2> 24025 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 24026 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|FINALIZED], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [8] contains [false]
[junit4] 2> 25413 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 25413 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|FINALIZED]]} size 0
[junit4] 1> Working on pass [9] contains [false]
[junit4] 2> 25468 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW]]} size 0
[junit4] 2> 25468 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcc491f6-b624-45d8-908c-25c5d43538c7:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 26263 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
[junit4] OK 19.9s J2 | HdfsDirectoryTest.testRandomAccessWrites
[junit4] 2> 26276 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEOF
[junit4] 2> 26317 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38343 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7ac2e126-6cca-4231-92db-e47795a03d28:NORMAL:127.0.0.1:39965|RBW], ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|RBW]]} size 0
[junit4] 2> 26317 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39965 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-6fc75186-30fc-47c4-912f-d1ff03447c2a:NORMAL:127.0.0.1:38343|RBW], ReplicaUC[[DISK]DS-49c380f5-b2df-4cb4-a905-977ef6cc3ba4:NORMAL:127.0.0.1:39965|FINALIZED]]} size 0
[junit4] 2> 26319 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEOF
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=FA7B62D62CF28EF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ckb-IQ -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.06s J2 | HdfsDirectoryTest.testEOF <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
[junit4] > at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 26324 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 26356 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:40825) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1942894219-88.99.242.108-1534924470550 (Datanode Uuid 38471165-7440-48ef-aa0f-72d2876258dd) service to localhost.localdomain/127.0.0.1:40825
[junit4] 2> 26376 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 26478 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 26486 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 26588 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:40825) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 26588 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:40825) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1942894219-88.99.242.108-1534924470550 (Datanode Uuid e62c8169-5e9d-4d84-ac49-1b28f5042ae4) service to localhost.localdomain/127.0.0.1:40825
[junit4] 2> 26600 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001
[junit4] 2> Aug 22, 2018 7:54:54 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 66 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=683, maxMBSortInHeap=6.551176223584393, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1dceb1f4), locale=ckb-IQ, timezone=America/Sao_Paulo
[junit4] 2> NOTE: Linux 4.15.0-32-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=6,free=450601016,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [HdfsDirectoryTest]
[junit4] Completed [1/5 (1!)] on J2 in 35.78s, 4 tests, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
[junit4] 2> 1986 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/init-core-data-001
[junit4] 2> 2104 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2140 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.e.j.u.log Logging initialized @٢١٦٥ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2146 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2660 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-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> 3318 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 3606 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Logging to org.apache.logging.slf4j.Log4jLogger@49b341a8 via org.mortbay.log.Slf4jLog
[junit4] 2> 3640 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 3787 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 3821 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_localdomain_38681_hdfs____.o1wl4s/webapp
[junit4] 2> 3963 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:38681
[junit4] 2> 4382 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4391 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 4396 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_33761_datanode____dwjsft/webapp
[junit4] 2> 4488 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33761
[junit4] 2> 4507 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] i.n.u.i.PlatformDependent Your platform does not provide complete low-level API for accessing direct buffers reliably. Unless explicitly requested, heap buffer will always be preferred to avoid potential system unstability.
[junit4] 2> 4719 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 4721 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log jetty-6.1.26
[junit4] 2> 4733 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Extract jar:file:/home/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.4-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_38861_datanode____.3doqln/webapp
[junit4] 2> 4812 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38861
[junit4] 2> 5454 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:43239) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 5454 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J0/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:43239) [ ] o.a.h.h.s.d.DirectoryScanner dfs.datanode.directoryscan.throttle.limit.ms.per.sec set to value below 1 ms/sec. Assuming default value of 1000
[junit4] 2> 5519 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d913002fa899: from storage DS-d864f05f-ca9c-4152-a528-fa95beefe1cd node DatanodeRegistration(127.0.0.1:39195, datanodeUuid=760cd1a4-ea51-4a2b-a449-79996638ff34, infoPort=39989, infoSecurePort=0, ipcPort=38553, storageInfo=lv=-56;cid=testClusterID;nsid=554263091;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs
[junit4] 2> 5520 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d913003af3b5: from storage DS-52920242-1a30-4110-8f0a-c795f295a097 node DatanodeRegistration(127.0.0.1:38719, datanodeUuid=4dc9e98c-a0ff-4f6a-a729-67fd92846d70, infoPort=33479, infoSecurePort=0, ipcPort=33529, storageInfo=lv=-56;cid=testClusterID;nsid=554263091;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs
[junit4] 2> 5521 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d913002fa899: from storage DS-0f38eace-32bc-440c-8e48-4007b8fc64a5 node DatanodeRegistration(127.0.0.1:39195, datanodeUuid=760cd1a4-ea51-4a2b-a449-79996638ff34, infoPort=39989, infoSecurePort=0, ipcPort=38553, storageInfo=lv=-56;cid=testClusterID;nsid=554263091;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 5521 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0x1d913003af3b5: from storage DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f node DatanodeRegistration(127.0.0.1:38719, datanodeUuid=4dc9e98c-a0ff-4f6a-a729-67fd92846d70, infoPort=33479, infoSecurePort=0, ipcPort=33529, storageInfo=lv=-56;cid=testClusterID;nsid=554263091;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 5682 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWritingAndReadingAFile
[junit4] 2> 5853 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741825_1001{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW]]} size 4
[junit4] 2> 5854 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741825_1001 size 4
[junit4] 2> 6311 INFO (IPC Server handler 0 on 43239) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:38719 127.0.0.1:39195
[junit4] 2> 6315 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWritingAndReadingAFile
[junit4] OK 0.75s J0 | HdfsDirectoryTest.testWritingAndReadingAFile
[junit4] 2> 6419 INFO (TEST-HdfsDirectoryTest.testRename-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRename
[junit4] 2> 6486 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 2> 6486 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 2> 6514 INFO (IPC Server handler 6 on 43239) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741826_1002 127.0.0.1:39195 127.0.0.1:38719
[junit4] 2> 6516 INFO (TEST-HdfsDirectoryTest.testRename-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRename
[junit4] OK 0.11s J0 | HdfsDirectoryTest.testRename
[junit4] 2> 6532 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
[junit4] 1> Working on pass [0] contains [false]
[junit4] 2> 6741 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|RBW]]} size 0
[junit4] 2> 6748 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|RBW]]} size 0
[junit4] 2> 7272 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@329d6418) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:38719 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 2> 10273 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@329d6418) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:39195 to delete [blk_1073741825_1001, blk_1073741826_1002]
[junit4] 1> Working on pass [1] contains [false]
[junit4] 2> 10761 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW]]} size 0
[junit4] 2> 10761 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 1> Working on pass [2] contains [false]
[junit4] 2> 13804 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 2> 13805 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|FINALIZED], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|FINALIZED]]} size 0
[junit4] 1> Working on pass [3] contains [false]
[junit4] 2> 14820 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|FINALIZED]]} size 0
[junit4] 2> 14821 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|FINALIZED]]} size 0
[junit4] 1> Working on pass [4] contains [false]
[junit4] 2> 19682 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW]]} size 0
[junit4] 2> 19683 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW]]} size 0
[junit4] 1> Working on pass [5] contains [false]
[junit4] 2> 21161 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 2> 21162 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741832_1008{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 1> Working on pass [6] contains [false]
[junit4] 2> 23702 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|FINALIZED]]} size 0
[junit4] 2> 23702 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|FINALIZED]]} size 0
[junit4] 1> Working on pass [7] contains [false]
[junit4] 2> 24254 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW]]} size 0
[junit4] 2> 24255 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:38719 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-0f38eace-32bc-440c-8e48-4007b8fc64a5:NORMAL:127.0.0.1:39195|RBW], ReplicaUC[[DISK]DS-dbddd7c3-be0e-4dc5-88b6-d052bd93ff1f:NORMAL:127.0.0.1:38719|FINALIZED]]} size 0
[junit4] 1> Working on pass [8] contains [false]
[junit4] 2> 25636 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:39195 is added to blk_1073741835_1011{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-52920242-1a30-4110-8f0a-c795f295a097:NORMAL:127.0.0.1:38719|RBW], ReplicaUC[[DISK]DS-d864f05f-ca9c-4152-a528-fa95beefe1cd:NORMAL:127.0.0.1:39195|RBW]]} size 5994301
[junit4] 2> 25636 INFO (Block rep
[...truncated too long message...]
(Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34231 is added to blk_1073741835_1011 size 5994301
[junit4] 1> Working on pass [9] contains [false]
[junit4] 2> 48583 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34231 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-67dfc34f-3c65-434e-9f7e-93897f6dc07f:NORMAL:127.0.0.1:36321|RBW], ReplicaUC[[DISK]DS-bcada77b-201e-4841-8593-363bf72e2e7f:NORMAL:127.0.0.1:34231|FINALIZED]]} size 0
[junit4] 2> 48584 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36321 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-67dfc34f-3c65-434e-9f7e-93897f6dc07f:NORMAL:127.0.0.1:36321|RBW], ReplicaUC[[DISK]DS-bcada77b-201e-4841-8593-363bf72e2e7f:NORMAL:127.0.0.1:34231|FINALIZED]]} size 0
[junit4] 2> 49309 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
[junit4] OK 12.0s J2 | HdfsDirectoryTest.testRandomAccessWrites
[junit4] 2> 49319 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEOF
[junit4] 2> 49355 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:36321 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-37a69657-a2c4-4a29-b91f-9420cde1e3ea:NORMAL:127.0.0.1:34231|RBW], ReplicaUC[[DISK]DS-e9d97260-e631-4037-9ee7-21fd573340fe:NORMAL:127.0.0.1:36321|FINALIZED]]} size 0
[junit4] 2> 49355 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34231 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-37a69657-a2c4-4a29-b91f-9420cde1e3ea:NORMAL:127.0.0.1:34231|RBW], ReplicaUC[[DISK]DS-e9d97260-e631-4037-9ee7-21fd573340fe:NORMAL:127.0.0.1:36321|FINALIZED]]} size 0
[junit4] 2> 49357 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[FA7B62D62CF28EF7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEOF
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=FA7B62D62CF28EF7 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ckb-IQ -Dtests.timezone=America/Sao_Paulo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.05s J2 | HdfsDirectoryTest.testEOF <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([FA7B62D62CF28EF7:6B1020DE6ED6288B]:0)
[junit4] > at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:159)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:151)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 49359 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 49373 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 49475 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:39239) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 49475 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:39239) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1497968553-88.99.242.108-1534924504200 (Datanode Uuid 91179769-4751-4915-a6a4-6ab0ab4f1621) service to localhost.localdomain/127.0.0.1:39239
[junit4] 2> 49477 WARN (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 49488 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 49590 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:39239) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 49590 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:39239) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1497968553-88.99.242.108-1534924504200 (Datanode Uuid 8333e673-7ab1-4072-bffd-cdea419d0bb5) service to localhost.localdomain/127.0.0.1:39239
[junit4] 2> 49596 INFO (SUITE-HdfsDirectoryTest-seed#[FA7B62D62CF28EF7]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_FA7B62D62CF28EF7-002
[junit4] 2> Aug 22, 2018 7:55:17 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 66 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=683, maxMBSortInHeap=6.551176223584393, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@513b4a35), locale=ckb-IQ, timezone=America/Sao_Paulo
[junit4] 2> NOTE: Linux 4.15.0-32-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=2,free=355012456,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [HdfsDirectoryTest, HdfsDirectoryTest]
[junit4] Completed [5/5 (5!)] on J2 in 22.81s, 4 tests, 1 error <<< FAILURES!
[...truncated 15 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 5 errors [seed: FA7B62D62CF28EF7]
Total time: 1 minute 1 second
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 5/5 failed: org.apache.solr.store.hdfs.HdfsDirectoryTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=5eab1c3c688a0d8db650c657567f197fb3dcf181, workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #22720
Recording test results
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2