You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/10/09 06:07:37 UTC
[JENKINS] Lucene-Solr-7.x-Linux (64bit/jdk-9) - Build # 576 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/576/
Java: 64bit/jdk-9 -XX:-UseCompressedOops -XX:+UseParallelGC --illegal-access=deny
1 tests failed.
FAILED: org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([E59A0BD1AF7D7725:74F149D9ED59D159]:0)
at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:158)
at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:150)
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 13201 lines...]
[junit4] Suite: org.apache.solr.store.hdfs.HdfsDirectoryTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/init-core-data-001
[junit4] 2> 1636847 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 1636847 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1636848 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-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> 1636848 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 1> Formatting using clusterid: testClusterID
[junit4] 2> 1636928 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties
[junit4] 2> 1636933 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1636935 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log jetty-6.1.x
[junit4] 2> 1636939 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-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_45125_hdfs____d7oxd9/webapp
[junit4] 2> 1637013 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:45125
[junit4] 2> 1637071 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1637072 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log jetty-6.1.x
[junit4] 2> 1637076 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-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_46711_datanode____.i9qbic/webapp
[junit4] 2> 1637146 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46711
[junit4] 2> 1637169 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j
[junit4] 2> 1637170 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log jetty-6.1.x
[junit4] 2> 1637172 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-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_32877_datanode____9symrk/webapp
[junit4] 2> 1637232 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:32877
[junit4] 2> 1637240 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] 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> 1637246 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0xc3ec8f21fba33: from storage DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8 node DatanodeRegistration(127.0.0.1:34787, datanodeUuid=142fc76e-66e7-415d-9446-28103de2d1d9, infoPort=39683, infoSecurePort=0, ipcPort=33353, storageInfo=lv=-56;cid=testClusterID;nsid=822018429;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 1637246 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0xc3ec8f21fba33: from storage DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004 node DatanodeRegistration(127.0.0.1:34787, datanodeUuid=142fc76e-66e7-415d-9446-28103de2d1d9, infoPort=39683, infoSecurePort=0, ipcPort=33353, storageInfo=lv=-56;cid=testClusterID;nsid=822018429;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 1637325 ERROR (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] 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> 1637328 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0xc3ec8f711b1fa: from storage DS-c630d304-8042-414b-93cf-754573ba138e node DatanodeRegistration(127.0.0.1:34715, datanodeUuid=e52da5de-3df7-40eb-ae0d-ba6224179fd9, infoPort=46775, infoSecurePort=0, ipcPort=35273, storageInfo=lv=-56;cid=testClusterID;nsid=822018429;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs
[junit4] 2> 1637329 INFO (Block report processor) [ ] BlockStateChange BLOCK* processReport 0xc3ec8f711b1fa: from storage DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620 node DatanodeRegistration(127.0.0.1:34715, datanodeUuid=e52da5de-3df7-40eb-ae0d-ba6224179fd9, infoPort=46775, infoSecurePort=0, ipcPort=35273, storageInfo=lv=-56;cid=testClusterID;nsid=822018429;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs
[junit4] 2> 1637367 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWritingAndReadingAFile
[junit4] 2> 1637386 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW]]} size 0
[junit4] 2> 1637387 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1637396 INFO (IPC Server handler 1 on 42277) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741825_1001 127.0.0.1:34715 127.0.0.1:34787
[junit4] 2> 1637397 INFO (TEST-HdfsDirectoryTest.testWritingAndReadingAFile-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWritingAndReadingAFile
[junit4] 2> 1637411 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRandomAccessWrites
[junit4] 1> Working on pass [0] contains [false]
[junit4] 2> 1637430 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW]]} size 0
[junit4] 2> 1637430 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW]]} size 0
[junit4] 2> 1640053 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@48cd2dc4) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:34787 to delete [blk_1073741825_1001]
[junit4] 1> Working on pass [1] contains [false]
[junit4] 2> 1641317 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1641317 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741827_1003{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 1> Working on pass [2] contains [false]
[junit4] 2> 1642045 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW]]} size 0
[junit4] 2> 1642048 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741828_1004{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 1> Working on pass [3] contains [false]
[junit4] 2> 1642315 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|FINALIZED]]} size 0
[junit4] 2> 1642315 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741829_1005{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|FINALIZED]]} size 0
[junit4] 2> 1643053 INFO (org.apache.hadoop.hdfs.server.blockmanagement.BlockManager$ReplicationMonitor@48cd2dc4) [ ] BlockStateChange BLOCK* BlockManager: ask 127.0.0.1:34715 to delete [blk_1073741825_1001]
[junit4] 1> Working on pass [4] contains [false]
[junit4] 2> 1644523 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW]]} size 0
[junit4] 2> 1644523 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741830_1006{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|FINALIZED]]} size 0
[junit4] 1> Working on pass [5] contains [false]
[junit4] 2> 1645003 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1645004 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741831_1007{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 1> Working on pass [6] contains [false]
[junit4] 2> 1646379 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741832_1008{UCState=COMMITTED, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW]]} size 841791
[junit4] 2> 1646379 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741832_1008 size 841791
[junit4] 1> Working on pass [7] contains [false]
[junit4] 2> 1648312 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|RBW]]} size 0
[junit4] 2> 1648312 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741833_1009{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|FINALIZED]]} size 0
[junit4] 1> Working on pass [8] contains [false]
[junit4] 2> 1650165 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW]]} size 0
[junit4] 2> 1650165 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741834_1010{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW], ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW]]} size 0
[junit4] 1> Working on pass [9] contains [false]
[junit4] 2> 1650602 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1650602 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741835_1011{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1652302 INFO (TEST-HdfsDirectoryTest.testRandomAccessWrites-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRandomAccessWrites
[junit4] 2> 1652311 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testEOF
[junit4] 2> 1652330 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741836_1012{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-d0ea59d8-f8e3-47ec-b0f3-d9ef0b74c0f8:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-dcf05d19-cb2e-4c86-8b1a-7796b5066620:NORMAL:127.0.0.1:34715|RBW]]} size 0
[junit4] 2> 1652331 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741836_1012 size 1315840
[junit4] 2> 1652331 INFO (TEST-HdfsDirectoryTest.testEOF-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testEOF
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsDirectoryTest -Dtests.method=testEOF -Dtests.seed=E59A0BD1AF7D7725 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-BW -Dtests.timezone=Etc/Zulu -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.03s J2 | HdfsDirectoryTest.testEOF <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([E59A0BD1AF7D7725:74F149D9ED59D159]:0)
[junit4] > at org.apache.lucene.store.RAMInputStream.readByte(RAMInputStream.java:69)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEof(HdfsDirectoryTest.java:158)
[junit4] > at org.apache.solr.store.hdfs.HdfsDirectoryTest.testEOF(HdfsDirectoryTest.java:150)
[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> 1652343 INFO (TEST-HdfsDirectoryTest.testRename-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRename
[junit4] 2> 1652351 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34715 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1652351 INFO (Block report processor) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:34787 is added to blk_1073741837_1013{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-55f7e5cf-4ab2-4815-bc3b-a94e8f288004:NORMAL:127.0.0.1:34787|RBW], ReplicaUC[[DISK]DS-c630d304-8042-414b-93cf-754573ba138e:NORMAL:127.0.0.1:34715|FINALIZED]]} size 0
[junit4] 2> 1652358 INFO (IPC Server handler 6 on 42277) [ ] BlockStateChange BLOCK* addToInvalidates: blk_1073741837_1013 127.0.0.1:34787 127.0.0.1:34715
[junit4] 2> 1652359 INFO (TEST-HdfsDirectoryTest.testRename-seed#[E59A0BD1AF7D7725]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRename
[junit4] 2> 1652359 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 1652379 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 1652480 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 1652480 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data3/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data4/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1950471810-88.99.242.108-1507527882789 (Datanode Uuid e52da5de-3df7-40eb-ae0d-ba6224179fd9) service to localhost.localdomain/127.0.0.1:42277
[junit4] 2> 1652481 WARN (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.a.h.h.s.d.DirectoryScanner DirectoryScanner: shutdown has been called
[junit4] 2> 1652495 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:0
[junit4] 2> 1652596 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] o.a.h.h.s.d.IncrementalBlockReportManager IncrementalBlockReportManager interrupted
[junit4] 2> 1652596 WARN (DataNode: [[[DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data1/, [DISK]file:/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001/tempDir-001/hdfsBaseDir/data/data2/]] heartbeating to localhost.localdomain/127.0.0.1:42277) [ ] o.a.h.h.s.d.DataNode Ending block pool service for: Block pool BP-1950471810-88.99.242.108-1507527882789 (Datanode Uuid 142fc76e-66e7-415d-9446-28103de2d1d9) service to localhost.localdomain/127.0.0.1:42277
[junit4] 2> 1652602 INFO (SUITE-HdfsDirectoryTest-seed#[E59A0BD1AF7D7725]-worker) [ ] o.m.log Stopped HttpServer2$SelectChannelConnectorWithSafeStartup@localhost.localdomain:0
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.store.hdfs.HdfsDirectoryTest_E59A0BD1AF7D7725-001
[junit4] 2> Oct 09, 2017 5:44:58 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=1521, maxMBSortInHeap=6.6903162663104165, sim=RandomSimilarity(queryNorm=true): {}, locale=en-BW, timezone=Etc/Zulu
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=2,free=77539224,total=516423680
[junit4] 2> NOTE: All tests run in this JVM: [SubstringBytesRefFilterTest, TestSolrQueryResponse, PingRequestHandlerTest, TestTrackingShardHandlerFactory, TestIndexSearcher, TestRuleBasedAuthorizationPlugin, DistributedFacetPivotLongTailTest, MoveReplicaHDFSTest, ExitableDirectoryReaderTest, ReturnFieldsTest, SolrXmlInZkTest, TestSolrCoreSnapshots, ConnectionManagerTest, TestDistribIDF, DeleteReplicaTest, BaseCdcrDistributedZkTest, TlogReplayBufferedWhileIndexingTest, DocumentBuilderTest, TestSolrConfigHandlerConcurrent, TestFoldingMultitermQuery, TestConfigSetProperties, TestLeaderElectionWithEmptyReplica, BlockJoinFacetSimpleTest, TestXmlQParserPlugin, TestLegacyFieldCache, DeleteInactiveReplicaTest, TestClassicSimilarityFactory, TestSolrCloudWithDelegationTokens, TestRecovery, DistributedQueueTest, TestStreamBody, ZkFailoverTest, FastVectorHighlighterTest, DistribCursorPagingTest, CursorMarkTest, CopyFieldTest, DistributedSuggestComponentTest, TestCustomStream, ReplicaListTransformerTest, ShardSplitTest, BJQParserTest, TestUninvertingReader, TestReplicationHandlerBackup, HdfsBasicDistributedZk2Test, WrapperMergePolicyFactoryTest, TestSolrConfigHandler, PKIAuthenticationIntegrationTest, LeaderElectionTest, DocValuesMissingTest, PreAnalyzedFieldManagedSchemaCloudTest, TermVectorComponentDistributedTest, UniqFieldsUpdateProcessorFactoryTest, UpdateLogTest, CollectionsAPISolrJTest, TestManagedSchemaAPI, StatsReloadRaceTest, TestMultiWordSynonyms, TestInfoStreamLogging, TestUniqueKeyFieldResource, TestGraphMLResponseWriter, NumericFieldsTest, TestQueryUtils, TestOnReconnectListenerSupport, TestCollationFieldDocValues, V2StandaloneTest, TestManagedResourceStorage, TestMinMaxOnMultiValuedField, ForceLeaderTest, TestRecoveryHdfs, PeerSyncReplicationTest, ReplaceNodeTest, TestReversedWildcardFilterFactory, TestMultiValuedNumericRangeQuery, SuggesterTest, TestCryptoKeys, OverriddenZkACLAndCredentialsProvidersTest, DateFieldTest, PrimitiveFieldTypeTest, TestLRUStatsCache, TestTrie, RollingRestartTest, TestLegacyFieldReuse, TestRebalanceLeaders, CollectionStateFormat2Test, HdfsSyncSliceTest, PreAnalyzedUpdateProcessorTest, TestNumericTokenStream, ConfigureRecoveryStrategyTest, TestRandomCollapseQParserPlugin, TestSimpleQParserPlugin, JSONWriterTest, TestFastWriter, TestEmbeddedSolrServerConstructors, TestPayloadCheckQParserPlugin, BasicDistributedZk2Test, ShowFileRequestHandlerTest, TestJmxIntegration, SuggestComponentContextFilterQueryTest, SortByFunctionTest, TestReload, TestSchemaSimilarityResource, HdfsDirectoryTest]
[junit4] Completed [500/734 (1!)] on J2 in 25.72s, 4 tests, 1 error <<< FAILURES!
[...truncated 40538 lines...]
[JENKINS] Lucene-Solr-7.x-Linux (64bit/jdk-9) - Build # 577 - Still
Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/577/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseSerialGC --illegal-access=deny
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.analytics.NoFacetCloudTest
Error Message:
Could not find collection:collection1
Stack Trace:
java.lang.AssertionError: Could not find collection:collection1
at __randomizedtesting.SeedInfo.seed([56E53D9DD3C2B0CD]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNotNull(Assert.java:526)
at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
at org.apache.solr.analytics.AbstractAnalyticsStatsCloudTest.setupCluster(AbstractAnalyticsStatsCloudTest.java:76)
at org.apache.solr.analytics.NoFacetCloudTest.populate(NoFacetCloudTest.java:62)
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$6.evaluate(RandomizedRunner.java:874)
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 16845 lines...]
[junit4] Suite: org.apache.solr.analytics.NoFacetCloudTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/contrib/solr-analytics/test/J2/temp/solr.analytics.NoFacetCloudTest_56E53D9DD3C2B0CD-001/init-core-data-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1064, maxMBSortInHeap=5.98115753111498, sim=RandomSimilarity(queryNorm=false): {}, locale=en-KE, timezone=Europe/Busingen
[junit4] 2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=438138640,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [FacetSortingTest, NoFacetCloudTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=NoFacetCloudTest -Dtests.seed=56E53D9DD3C2B0CD -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-KE -Dtests.timezone=Europe/Busingen -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J2 | NoFacetCloudTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: Could not find collection:collection1
[junit4] > at __randomizedtesting.SeedInfo.seed([56E53D9DD3C2B0CD]:0)
[junit4] > at org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:155)
[junit4] > at org.apache.solr.analytics.AbstractAnalyticsStatsCloudTest.setupCluster(AbstractAnalyticsStatsCloudTest.java:76)
[junit4] > at org.apache.solr.analytics.NoFacetCloudTest.populate(NoFacetCloudTest.java:62)
[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] Completed [16/17 (1!)] on J2 in 43.71s, 0 tests, 1 failure <<< FAILURES!
[...truncated 36896 lines...]