Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6769/
Java: 64bit/jdk-9-ea+178 -XX:+UseCompressedOops -XX:+UseParallelGC
--illegal-access=deny
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.TestCloudRecovery
Error Message:
Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another
process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another
process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another
process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another
process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of
attempts):
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001:
java.nio.file.FileSystemException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another process.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001
at __randomizedtesting.SeedInfo.seed([EBB955D2BDE8533E]:0)
at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
at
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
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:
junit.framework.TestSuite.org.apache.solr.client.solrj.SolrExceptionTest
Error Message:
The test or suite printed 9250 bytes to stdout and stderr, even though the
limit was set to 8192 bytes. Increase the limit with @Limit, ignore it
completely with @SuppressSysoutChecks or run with -Dtests.verbose=true
Stack Trace:
java.lang.AssertionError: The test or suite printed 9250 bytes to stdout and
stderr, even though the limit was set to 8192 bytes. Increase the limit with
@Limit, ignore it completely with @SuppressSysoutChecks or run with
-Dtests.verbose=true
at __randomizedtesting.SeedInfo.seed([1A9EB20CE75D9E17]:0)
at
org.apache.lucene.util.TestRuleLimitSysouts.afterIfSuccessful(TestRuleLimitSysouts.java:211)
at
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterIfSuccessful(TestRuleAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:37)
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 11557 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCloudRecovery
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\init-core-data-001
[junit4] 2> 884049 WARN
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 884049 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true)
w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 884050 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0,
clientAuth=0.0/0.0)
[junit4] 2> 884052 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 884053 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001
[junit4] 2> 884053 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 884053 INFO (Thread-1520) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 884053 INFO (Thread-1520) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 884056 ERROR (Thread-1520) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 884153 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:59721
[junit4] 2> 884162 INFO (jetty-launcher-2100-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 884162 INFO (jetty-launcher-2100-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 884163 INFO (jetty-launcher-2100-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@399f20a6{/solr,null,AVAILABLE}
[junit4] 2> 884163 INFO (jetty-launcher-2100-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@4671276d{/solr,null,AVAILABLE}
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@198e48de{SSL,[ssl,
http/1.1]}{127.0.0.1:?????}
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@74468355{SSL,[ssl,
http/1.1]}{127.0.0.1:?????}
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-1) [ ]
o.e.j.s.Server Started @??????ms
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-2) [ ]
o.e.j.s.Server Started @??????ms
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=59726}
[junit4] 2> 884166 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=59725}
[junit4] 2> 884167 ERROR (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 884167 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 884167 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 884167 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 884167 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-22T03:40:08.909869500Z
[junit4] 2> 884169 ERROR (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 884169 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 884169 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 884169 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 884169 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-22T03:40:08.911470300Z
[junit4] 2> 884171 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 884172 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 884175 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15d686188de0001, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 884179 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59721/solr
[junit4] 2> 884180 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59721/solr
[junit4] 2> 884184 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15d686188de0003, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 884185 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15d686188de0004, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 884260 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 884260 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 884261 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59726_solr
[junit4] 2> 884261 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:59725_solr
[junit4] 2> 884262 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=98349534989385734-127.0.0.1:59726_solr-n_0000000000) starting
[junit4] 2> 884265 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 884265 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 884270 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:59726_solr
[junit4] 2> 884271 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 884271 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 884359 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884360 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59725.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884361 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884361 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884362 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59725.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884362 INFO (jetty-launcher-2100-thread-1) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.
[junit4] 2> 884362 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59725.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 884363 INFO (jetty-launcher-2100-thread-2) [ ]
o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\.
[junit4] 2> 884392 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 884393 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:59721/solr ready
[junit4] 2> 884410 INFO (qtp1180543967-9744) [ ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
pullReplicas=0&replicationFactor=2&collection.configName=config&maxShardsPerNode=2&name=collection1&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 884412 INFO (OverseerThreadFactory-3384-thread-1) [ ]
o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 884518 INFO
(OverseerStateUpdate-98349534989385734-127.0.0.1:59726_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:59726/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 884521 INFO
(OverseerStateUpdate-98349534989385734-127.0.0.1:59726_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:59725/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 884522 INFO
(OverseerStateUpdate-98349534989385734-127.0.0.1:59726_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:59726/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 884524 INFO
(OverseerStateUpdate-98349534989385734-127.0.0.1:59726_solr-n_0000000000) [
] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:59725/solr";,
[junit4] 2> "type":"NRT"}
[junit4] 2> 884741 INFO (qtp1180543967-9740) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node1&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 884742 INFO (qtp1180543967-9740) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 884742 INFO (qtp1126316575-9737) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 884743 INFO (qtp1126316575-9737) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 884743 INFO (qtp1180543967-9736) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 884745 INFO (qtp1126316575-9730) [ ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 884856 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 884856 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 884857 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 884857 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 885758 INFO (qtp1126316575-9730) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 885758 INFO (qtp1180543967-9736) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 885759 INFO (qtp1126316575-9737) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 885763 INFO (qtp1180543967-9736) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n1] Schema name=minimal
[junit4] 2> 885763 INFO (qtp1126316575-9730) [ ] o.a.s.s.IndexSchema
[collection1_shard2_replica_n2] Schema name=minimal
[junit4] 2> 885764 INFO (qtp1126316575-9737) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_n2] Schema name=minimal
[junit4] 2> 885764 INFO (qtp1180543967-9736) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 885764 INFO (qtp1180543967-9736) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n1' using configuration from
collection collection1, trusted=true
[junit4] 2> 885764 INFO (qtp1126316575-9730) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 885764 INFO (qtp1126316575-9730) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica_n2' using configuration from
collection collection1, trusted=true
[junit4] 2> 885765 INFO (qtp1180543967-9736) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59726.solr.core.collection1.shard2.replica_n1' (registry
'solr.core.collection1.shard2.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 885765 INFO (qtp1126316575-9730) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59725.solr.core.collection1.shard2.replica_n2' (registry
'solr.core.collection1.shard2.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 885765 INFO (qtp1180543967-9736) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 885765 INFO (qtp1126316575-9730) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 885765 INFO (qtp1180543967-9736) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.\collection1_shard2_replica_n1\data\]
[junit4] 2> 885765 INFO (qtp1126316575-9730) [ ] o.a.s.c.SolrCore
[[collection1_shard2_replica_n2] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard2_replica_n2],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\.\collection1_shard2_replica_n2\data\]
[junit4] 2> 885765 INFO (qtp1126316575-9737) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 885765 INFO (qtp1126316575-9737) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n2' using configuration from
collection collection1, trusted=true
[junit4] 2> 885766 INFO (qtp1126316575-9737) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59725.solr.core.collection1.shard1.replica_n2' (registry
'solr.core.collection1.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 885766 INFO (qtp1126316575-9737) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 885766 INFO (qtp1126316575-9737) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_n2] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\.\collection1_shard1_replica_n2\data\]
[junit4] 2> 885770 INFO (qtp1180543967-9740) [ ] o.a.s.c.SolrConfig
Using Lucene MatchVersion: 8.0.0
[junit4] 2> 885779 INFO (qtp1180543967-9740) [ ] o.a.s.s.IndexSchema
[collection1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 885782 INFO (qtp1180543967-9740) [ ] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 885782 INFO (qtp1180543967-9740) [ ] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica_n1' using configuration from
collection collection1, trusted=true
[junit4] 2> 885782 INFO (qtp1180543967-9740) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59726.solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 885783 INFO (qtp1180543967-9740) [ ] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 885783 INFO (qtp1180543967-9740) [ ] o.a.s.c.SolrCore
[[collection1_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.\collection1_shard1_replica_n1\data\]
[junit4] 2> 885847 INFO (qtp1180543967-9736) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885847 INFO (qtp1180543967-9736) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885848 INFO (qtp1180543967-9736) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 885848 INFO (qtp1180543967-9736) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 885855 INFO (qtp1126316575-9737) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885855 INFO (qtp1126316575-9737) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885856 INFO (qtp1126316575-9737) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 885856 INFO (qtp1126316575-9737) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 885860 INFO (qtp1180543967-9736) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2927dd14[collection1_shard2_replica_n1] main]
[junit4] 2> 885860 INFO (qtp1180543967-9740) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885860 INFO (qtp1180543967-9740) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885860 INFO (qtp1180543967-9736) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 885861 INFO (qtp1180543967-9736) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 885861 INFO (qtp1180543967-9736) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885861 INFO (qtp1180543967-9736) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1573592561722851328
[junit4] 2> 885861 INFO (qtp1180543967-9740) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 885861 INFO (qtp1180543967-9740) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 885862 INFO (qtp1126316575-9737) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@28d33f89[collection1_shard1_replica_n2] main]
[junit4] 2> 885864 INFO (qtp1126316575-9737) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 885864 INFO (qtp1126316575-9737) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 885866 INFO (searcherExecutor-3389-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n1] Registered new searcher
Searcher@2927dd14[collection1_shard2_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885866 INFO (qtp1126316575-9737) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885866 INFO (qtp1126316575-9737) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1573592561728094208
[junit4] 2> 885867 INFO (qtp1180543967-9740) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@648a43e3[collection1_shard1_replica_n1] main]
[junit4] 2> 885867 INFO (searcherExecutor-3391-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n2] Registered new searcher
Searcher@28d33f89[collection1_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885869 INFO (qtp1180543967-9740) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 885869 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard2: total=2 found=1 timeoutin=9999ms
[junit4] 2> 885870 INFO (qtp1180543967-9740) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 885870 INFO (qtp1180543967-9740) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885871 INFO (qtp1180543967-9740) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1573592561733337088
[junit4] 2> 885872 INFO (searcherExecutor-3392-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher
Searcher@648a43e3[collection1_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885875 INFO (qtp1126316575-9737) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 885875 INFO (qtp1126316575-9737) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 885875 INFO (qtp1126316575-9737) [ ] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/
[junit4] 2> 885876 INFO (qtp1126316575-9737) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard1_replica_n2 url=https://127.0.0.1:59725/solr
START replicas=[https://127.0.0.1:59726/solr/collection1_shard1_replica_n1/]
nUpdates=100
[junit4] 2> 885876 INFO (qtp1126316575-9737) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard1_replica_n2 url=https://127.0.0.1:59725/solr
DONE. We have no versions. sync failed.
[junit4] 2> 885880 INFO (qtp1180543967-9746) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 885880 INFO (qtp1126316575-9737) [ ] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 885881 INFO (qtp1126316575-9737) [ ]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 885881 INFO (qtp1126316575-9737) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 885892 INFO (qtp1126316575-9737) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/ shard1
[junit4] 2> 885892 INFO (qtp1126316575-9730) [ ] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 885892 INFO (qtp1126316575-9730) [ ] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 885894 INFO (qtp1126316575-9730) [ ] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 885894 INFO (qtp1126316575-9730) [ ] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 885897 INFO (qtp1126316575-9730) [ ]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@40e61fe3[collection1_shard2_replica_n2] main]
[junit4] 2> 885898 INFO (qtp1126316575-9730) [ ]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/config
[junit4] 2> 885899 INFO (qtp1126316575-9730) [ ]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/config
[junit4] 2> 885899 INFO (qtp1126316575-9730) [ ]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 885900 INFO (qtp1126316575-9730) [ ] o.a.s.u.UpdateLog
Could not find max version in index or recent updates, using new clock
1573592561763745792
[junit4] 2> 885902 INFO (searcherExecutor-3390-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher
Searcher@40e61fe3[collection1_shard2_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 885999 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 885999 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 885999 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 885999 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886046 INFO (qtp1126316575-9737) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 886047 INFO (qtp1126316575-9737) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1304
[junit4] 2> 886150 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886150 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886150 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886150 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886370 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 886370 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 886370 INFO (qtp1180543967-9736) [ ] o.a.s.c.SyncStrategy
Sync replicas to https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/
[junit4] 2> 886371 INFO (qtp1180543967-9736) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard2_replica_n1 url=https://127.0.0.1:59726/solr
START replicas=[https://127.0.0.1:59725/solr/collection1_shard2_replica_n2/]
nUpdates=100
[junit4] 2> 886371 INFO (qtp1180543967-9736) [ ] o.a.s.u.PeerSync
PeerSync: core=collection1_shard2_replica_n1 url=https://127.0.0.1:59726/solr
DONE. We have no versions. sync failed.
[junit4] 2> 886375 INFO (qtp1126316575-9739) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 886375 INFO (qtp1180543967-9736) [ ] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 886375 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 886375 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 886379 INFO (qtp1180543967-9736) [ ]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/ shard2
[junit4] 2> 886483 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886483 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886483 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886483 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886531 INFO (qtp1180543967-9736) [ ] o.a.s.c.ZkController
I am the leader, no recovery necessary
[junit4] 2> 886531 INFO (qtp1180543967-9736) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1788
[junit4] 2> 886636 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886636 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886636 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886636 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 886878 INFO (qtp1180543967-9740) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node1&collection.configName=config&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2136
[junit4] 2> 886903 INFO (qtp1126316575-9730) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=config&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2160
[junit4] 2> 886909 INFO (qtp1180543967-9744) [ ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 887010 INFO (zkCallback-2111-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 887010 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 887010 INFO (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 887010 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [2])
[junit4] 2> 887910 INFO (qtp1180543967-9744) [ ] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/collections
params={pullReplicas=0&replicationFactor=2&collection.configName=config&maxShardsPerNode=2&name=collection1&nrtReplicas=2&action=CREATE&numShards=2&tlogReplicas=0&wt=javabin&version=2}
status=0 QTime=3499
[junit4] 2> 887910 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 887911 INFO
(SUITE-TestCloudRecovery-seed#[EBB955D2BDE8533E]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 887922 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting corruptedLogTest
[junit4] 2> 887942 INFO (qtp1180543967-9734) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&_version_=-1573592563889209344&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:*
(-1573592563889209344)} 0 11
[junit4] 2> 887942 INFO (qtp1126316575-9743) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1573592563889209344)} 0 16
[junit4] 2> 887943 INFO (qtp1126316575-9732) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&_version_=-1573592563887112192&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1573592563887112192)} 0 11
[junit4] 2> 887943 INFO (qtp1180543967-9738) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*
(-1573592563887112192)} 0 19
[junit4] 2> 887945 INFO (qtp1126316575-9730) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573592563908083712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 887945 INFO (qtp1126316575-9737) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573592563908083712,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 887945 INFO (qtp1126316575-9730) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 887945 INFO (qtp1126316575-9737) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 887946 INFO (qtp1126316575-9737) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 887946 INFO (qtp1126316575-9737) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 887948 INFO (qtp1126316575-9730) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 887948 INFO (qtp1126316575-9730) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 887949 INFO (qtp1180543967-9740) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573592563912278016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 887949 INFO (qtp1180543967-9740) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 887949 INFO (qtp1180543967-9740) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 887949 INFO (qtp1180543967-9740) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 887951 INFO (qtp1180543967-9736) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{_version_=1573592563914375168,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 887951 INFO (qtp1180543967-9736) [ ]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 887951 INFO (qtp1180543967-9736) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 887951 INFO (qtp1180543967-9736) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 887951 INFO (qtp1180543967-9733) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update
params={_stateVer_=collection1:7&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 7
[junit4] 2> 887960 INFO (qtp1180543967-9744) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{add=[1000
(1573592563917520896)]} 0 2
[junit4] 2> 887960 INFO (qtp1126316575-9743) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1000
(1573592563917520896)]} 0 6
[junit4] 2> 887968 INFO (qtp1126316575-9739) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[1001
(1573592563928006656)]} 0 1
[junit4] 2> 887968 INFO (qtp1180543967-9746) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1001
(1573592563928006656)]} 0 4
[junit4] 2> 887970 INFO (qtp1180543967-9738) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{add=[0
(1573592563933249536)]} 0 0
[junit4] 2> 887970 INFO (qtp1126316575-9732) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[0
(1573592563933249536)]} 0 1
[junit4] 2> 887972 INFO (qtp1180543967-9734) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{add=[1
(1573592563935346688)]} 0 0
[junit4] 2> 887972 INFO (qtp1126316575-9737) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[1
(1573592563935346688)]} 0 1
[junit4] 2> 887973 INFO (qtp1126316575-9730) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[2
(1573592563937443840)]} 0 0
[junit4] 2> 887973 INFO (qtp1180543967-9736) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[2
(1573592563937443840)]} 0 0
[junit4] 2> 887975 INFO (qtp1126316575-9735) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[3
(1573592563937443841)]} 0 0
[junit4] 2> 887975 INFO (qtp1180543967-9733) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[3
(1573592563937443841)]} 0 0
[junit4] 2> 887977 INFO (qtp1180543967-9744) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{add=[4
(1573592563940589568)]} 0 0
[junit4] 2> 887977 INFO (qtp1126316575-9743) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[4
(1573592563940589568)]} 0 1
[junit4] 2> 887978 INFO (qtp1126316575-9732) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[5
(1573592563941638144)]} 0 0
[junit4] 2> 887978 INFO (qtp1180543967-9746) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[5
(1573592563941638144)]} 0 1
[junit4] 2> 887979 INFO (qtp1126316575-9737) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[6
(1573592563943735296)]} 0 0
[junit4] 2> 887979 INFO (qtp1180543967-9734) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[6
(1573592563943735296)]} 0 1
[junit4] 2> 887981 INFO (qtp1126316575-9730) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[7
(1573592563944783872)]} 0 0
[junit4] 2> 887981 INFO (qtp1180543967-9736) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[7
(1573592563944783872)]} 0 1
[junit4] 2> 887982 INFO (qtp1180543967-9733) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/&wt=javabin&version=2}{add=[8
(1573592563945832448)]} 0 0
[junit4] 2> 887982 INFO (qtp1126316575-9735) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n2]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[8
(1573592563945832448)]} 0 1
[junit4] 2> 887985 INFO (qtp1126316575-9742) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&wt=javabin&version=2}{add=[9
(1573592563947929600)]} 0 0
[junit4] 2> 887985 INFO (qtp1180543967-9738) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n1]
webapp=/solr path=/update params={wt=javabin&version=2}{add=[9
(1573592563947929600)]} 0 1
[junit4] 2> 887993 INFO (qtp1126316575-9737) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n2] webapp=/solr path=/select
params={df=text&distrib=false&_stateVer_=collection1:7&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:59725/solr/collection1_shard2_replica_n2/|https://127.0.0.1:59726/solr/collection1_shard2_replica_n1/&rows=10&version=2&q=*:*&NOW=1500694812727&isShard=true&wt=javabin}
hits=0 status=0 QTime=1
[junit4] 2> 887998 INFO (qtp1126316575-9735) [ ] o.a.s.c.S.Request
[collection1_shard1_replica_n2] webapp=/solr path=/select
params={df=text&distrib=false&_stateVer_=collection1:7&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:59725/solr/collection1_shard1_replica_n2/|https://127.0.0.1:59726/solr/collection1_shard1_replica_n1/&rows=10&version=2&q=*:*&NOW=1500694812727&isShard=true&wt=javabin}
hits=0 status=0 QTime=0
[junit4] 2> 887998 INFO (qtp1126316575-9732) [ ] o.a.s.c.S.Request
[collection1_shard2_replica_n2] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:7&wt=javabin&version=2} hits=0 status=0
QTime=13
[junit4] 2> 888002 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 59726
[junit4] 2> 888002 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1455300468
[junit4] 2> 888002 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 888003 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 888005 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 888006 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 888007 INFO (coreCloseExecutor-3409-thread-2) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@2ad8ceb9
[junit4] 2> 888007 INFO (coreCloseExecutor-3409-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5620abc9
[junit4] 2> 888018 INFO (coreCloseExecutor-3409-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard2.replica_n1, tag=1444981705
[junit4] 2> 888021 INFO (coreCloseExecutor-3409-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard2.leader, tag=1444981705
[junit4] 2> 888026 INFO (coreCloseExecutor-3409-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.replica_n1, tag=718851769
[junit4] 2> 888028 INFO (coreCloseExecutor-3409-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=718851769
[junit4] 2> 888034 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.Overseer Overseer
(id=98349534989385734-127.0.0.1:59726_solr-n_0000000000) closing
[junit4] 2> 888034 INFO
(OverseerStateUpdate-98349534989385734-127.0.0.1:59726_solr-n_0000000000) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59726_solr
[junit4] 2> 888038 WARN (zkCallback-2111-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 888044 INFO (zkCallback-2112-thread-3) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59725_solr
[junit4] 2> 888044 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 888046 INFO (zkCallback-2118-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 888047 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@198e48de{SSL,[ssl,
http/1.1]}{127.0.0.1:?}
[junit4] 2> 888050 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@4671276d{/solr,null,UNAVAILABLE}
[junit4] 2> 888053 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 888055 INFO (zkCallback-2112-thread-3) [ ]
o.a.s.c.Overseer Overseer
(id=98349534989385733-127.0.0.1:59725_solr-n_0000000001) starting
[junit4] 2> 888066 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.ChaosMonkey monkey: stop jetty! 59725
[junit4] 2> 888066 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=461086539
[junit4] 2> 888066 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node,
tag=null
[junit4] 2> 888068 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 888068 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 888075 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 888080 INFO (zkCallback-2112-thread-3) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 888080 INFO (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent
state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [1])
[junit4] 2> 888087 INFO (coreCloseExecutor-3413-thread-1) [ ]
o.a.s.c.SolrCore [collection1_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@38618ca6
[junit4] 2> 888093 INFO (coreCloseExecutor-3413-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard1.replica_n2, tag=945917094
[junit4] 2> 888097 INFO (coreCloseExecutor-3413-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard1.leader, tag=945917094
[junit4] 2> 888100 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.Overseer Overseer
(id=98349534989385733-127.0.0.1:59725_solr-n_0000000001) closing
[junit4] 2> 888100 INFO
(OverseerStateUpdate-98349534989385733-127.0.0.1:59725_solr-n_0000000001) [
] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59725_solr
[junit4] 2> 888103 WARN (zkCallback-2112-thread-1) [ ]
o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK:
[KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 888104 INFO (zkCallback-2118-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 890553 WARN (zkCallback-2112-thread-2) [ ]
o.a.s.c.SyncStrategy Closed, skipping sync up.
[junit4] 2> 890553 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.c.SolrCore [collection1_shard2_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@62ebaca3
[junit4] 2> 890554 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.collection1.shard2.replica_n2, tag=1659612323
[junit4] 2> 890559 INFO (zkCallback-2112-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.collection1.shard2.leader, tag=1659612323
[junit4] 2> 890561 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@74468355{SSL,[ssl,
http/1.1]}{127.0.0.1:?}
[junit4] 2> 890561 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@399f20a6{/solr,null,UNAVAILABLE}
[junit4] 2> 890567 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.ChaosMonkey monkey: starting jetty! 59726
[junit4] 2> 890568 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 890569 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@502712d3{/solr,null,AVAILABLE}
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@42a817f7{SSL,[ssl,
http/1.1]}{127.0.0.1:?????}
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.e.j.s.Server Started @??????ms
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=59726}
[junit4] 2> 890571 ERROR
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.0.0
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config
dir: null
[junit4] 2> 890571 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-07-22T03:40:15.313994300Z
[junit4] 2> 890575 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 890585 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59721/solr
[junit4] 2> 890614 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 890615 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59726_solr
[junit4] 2> 890616 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.Overseer Overseer
(id=98349534989385739-127.0.0.1:59726_solr-n_0000000002) starting
[junit4] 2> 890623 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:59726_solr
[junit4] 2> 890625 INFO
(OverseerStateUpdate-98349534989385739-127.0.0.1:59726_solr-n_0000000002) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 890626 INFO (zkCallback-2118-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 890693 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.node' (registry
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 890696 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.jvm' (registry
'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 890697 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_59726.solr.jetty' (registry
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 890699 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.CorePropertiesLocator Found 2 core definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.
[junit4] 2> 890700 INFO
(TEST-TestCloudRecovery.corruptedLogTest-seed#[EBB955D2BDE8533E]) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1_shard1_replica_n1,
collection1_shard2_replica_n1]
[junit4] 2> 890702 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 890714 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 890715 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 890719 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=minimal
[junit4] 2> 890719 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.s.IndexSchema [collection1_shard2_replica_n1] Schema name=minimal
[junit4] 2> 890724 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 890724 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 890725 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using
configuration from collection collection1, trusted=true
[junit4] 2> 890725 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n1' using
configuration from collection collection1, trusted=true
[junit4] 2> 890725 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59726.solr.core.collection1.shard1.replica_n1' (registry
'solr.core.collection1.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 890725 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 890725 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.\collection1_shard1_replica_n1\data\]
[junit4] 2> 890726 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_59726.solr.core.collection1.shard2.replica_n1' (registry
'solr.core.collection1.shard2.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@425927c2
[junit4] 2> 890726 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 890726 INFO (coreLoadExecutor-3422-thread-2) [ ]
o.a.s.c.SolrCore [[collection1_shard2_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\collection1_shard2_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node1\.\collection1_shard2_replica_n1\data\]
[junit4] 2> 890783 INFO (coreLoadExecutor-3422-thread-1) [ ]
o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.
[...truncated too long message...]
:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog\tlog.0000000000000000001:
The process cannot access the file because it is being used by another process.
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data\tlog
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2\data
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2\collection1_shard1_replica_n2
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001\node2
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001\tempDir-001
[junit4] >
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001:
java.nio.file.DirectoryNotEmptyException:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.TestCloudRecovery_EBB955D2BDE8533E-001
[junit4] > at
__randomizedtesting.SeedInfo.seed([EBB955D2BDE8533E]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] Completed [239/727 (1!)] on J1 in 23.30s, 2 tests, 1 error <<<
FAILURES!
[...truncated 2131 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExceptionTest
[junit4] 2> 0 INFO
(TEST-SolrExceptionTest.testSolrException-seed#[1A9EB20CE75D9E17]) [ ]
o.a.s.u.Java9InitHack Adding temporary workaround for Hadoop's Shell class to
allow running on Java 9 (please ignore any warnings/failures).
[junit4] 2> 36 ERROR
(TEST-SolrExceptionTest.testSolrException-seed#[1A9EB20CE75D9E17]) [ ]
o.a.h.u.Shell Failed to locate the winutils binary in the hadoop binary path
[junit4] 2> java.io.IOException: Could not locate executable
null\bin\winutils.exe in the Hadoop binaries.
[junit4] 2> at
org.apache.hadoop.util.Shell.getQualifiedBinPath(Shell.java:356)
[junit4] 2> at
org.apache.hadoop.util.Shell.getWinUtilsPath(Shell.java:371)
[junit4] 2> at org.apache.hadoop.util.Shell.<clinit>(Shell.java:364)
[junit4] 2> at java.base/java.lang.Class.forName0(Native Method)
[junit4] 2> at java.base/java.lang.Class.forName(Class.java:292)
[junit4] 2> at
org.apache.solr.util.Java9InitHack.initPrivileged(Java9InitHack.java:65)
[junit4] 2> at
java.base/java.security.AccessController.doPrivileged(Native Method)
[junit4] 2> at
org.apache.solr.util.Java9InitHack.initJava9(Java9InitHack.java:55)
[junit4] 2> at
org.apache.solr.SolrTestCaseJ4.<clinit>(SolrTestCaseJ4.java:168)
[junit4] 2> at
org.apache.solr.client.solrj.SolrExceptionTest.testSolrException(SolrExceptionTest.java:43)
[junit4] 2> at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at
java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
[junit4] 2> at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=333, maxMBSortInHeap=7.515211323630584,
sim=RandomSimilarity(queryNorm=false): {}, locale=twq-NE,
timezone=Asia/Srednekolymsk
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9
(64-bit)/cpus=3,threads=1,free=68596400,total=121634816
[junit4] 2> NOTE: All tests run in this JVM: [NotEvaluatorTest,
HttpSolrClientBuilderTest, SquareRootEvaluatorTest, TestDelegationTokenRequest,
PowerEvaluatorTest, TestFastInputStream, DocumentAnalysisResponseTest,
SolrExceptionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrExceptionTest
-Dtests.seed=1A9EB20CE75D9E17 -Dtests.slow=true -Dtests.locale=twq-NE
-Dtests.timezone=Asia/Srednekolymsk -Dtests.asserts=true
-Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | SolrExceptionTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: The test or suite
printed 9250 bytes to stdout and stderr, even though the limit was set to 8192
bytes. Increase the limit with @Limit, ignore it completely with
@SuppressSysoutChecks or run with -Dtests.verbose=true
[junit4] > at
__randomizedtesting.SeedInfo.seed([1A9EB20CE75D9E17]:0)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] Completed [11/135 (1!)] on J1 in 0.33s, 1 test, 1 failure <<<
FAILURES!
[...truncated 36002 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
