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
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
