You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/02/22 23:52:56 UTC
[JENKINS] Lucene-Solr-7.x-Windows (32bit/jdk1.8.0_144) - Build #
471 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/471/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseConcMarkSweepGC
5 tests failed.
FAILED: junit.framework.TestSuite.org.apache.lucene.store.TestMmapDirectory
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001
at __randomizedtesting.SeedInfo.seed([EA20D4E7B8F2C2BD]: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.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.lucene.store.TestNRTCachingDirectory
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001
at __randomizedtesting.SeedInfo.seed([EA20D4E7B8F2C2BD]: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.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.search.TestSearcherReuse
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001
at __randomizedtesting.SeedInfo.seed([C64A56B619D0439D]: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.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.servlet.DirectSolrConnectionTest
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001
at __randomizedtesting.SeedInfo.seed([C64A56B619D0439D]: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.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001
at __randomizedtesting.SeedInfo.seed([7D5E4982A67B9CE7]: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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 898 lines...]
[junit4] Suite: org.apache.lucene.store.TestNRTCachingDirectory
[junit4] IGNOR/A 0.00s J1 | TestNRTCachingDirectory.testPendingDeletions
[junit4] > Assumption #1: we can only install VirusCheckingFS on an FSDirectory
[junit4] IGNOR/A 0.00s J1 | TestNRTCachingDirectory.testFsyncDoesntCreateNewFiles
[junit4] > Assumption #1: test only works for FSDirectory subclasses
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=7, maxDocsPerChunk=983, blockSize=10), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=7, blockSize=10)), sim=RandomSimilarity(queryNorm=false): {titleTokenized=IB LL-D1, body=DFR I(n)L2}, locale=ar-LB, timezone=Jamaica
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=312246384,total=422400000
[junit4] 2> NOTE: All tests run in this JVM: [TestAutomatonQueryUnicode, TestSimilarity, Test2BSortedDocValuesFixedSorted, TestDocValuesScoring, TestNotDocIdSet, TestLogMergePolicy, TestOrdinalMap, TestForTooMuchCloning, TestTransactionRollback, TestIntsRef, TestPerFieldPostingsFormat, TestDirectory, TestEarlyTermination, TestPerFieldDocValuesFormat, TestBlockPostingsFormat3, TestFieldReuse, TestTermsEnum, TestLongPostings, TestSortRandom, TestTermRangeQuery, TestMaxTermFrequency, TestReadOnlyIndex, TestOfflineSorter, TestMultiFields, TestBasics, TestRollingUpdates, TestGeoUtils, TestLongBitSet, TestSpanOrQuery, Test4GBStoredFields, TestIntArrayDocIdSet, TestPostingsOffsets, TestConstantScoreQuery, TestBytesStore, TestGraphTokenizers, TestTermContext, TestPagedBytes, TestMultiTermsEnum, TestSingleInstanceLockFactory, TestCachingCollector, TestPerFieldPostingsFormat2, TestDuelingCodecsAtNight, TestFieldMaskingSpanQuery, TestIndexWriterDelete, TestLucene50CompoundFormat, TestNativeFSLockFactory, TestMergeSchedulerExternal, TestMultiDocValues, TestMultiset, TestFixedBitDocIdSet, TestOmitNorms, TestFilterDirectory, TestFutureObjects, TestMultiTermQueryRewrites, TestReaderClosed, Test2BPositions, TestIndexWriterLockRelease, TestDeterminism, TestStressDeletes, TestStopFilter, TestPackedInts, TestIndexWriterForceMerge, TestCharsRefBuilder, TestIntroSorter, TestDocumentsWriterStallControl, TestPayloads, TestBoostQuery, TestSearchAfter, TestSpanCollection, TestRadixSelector, TestStressAdvance, TestConcurrentMergeScheduler, TestNoMergeScheduler, TestSparseFixedBitDocIdSet, TestSpanFirstQuery, TestTermVectorsReader, TestIndexOrDocValuesQuery, TestIndexWriterMaxDocs, TestMathUtil, TestAxiomaticSimilarity, TestSynonymQuery, TestMultiCollector, TestAllFilesCheckIndexHeader, TestExitableDirectoryReader, Test2BPostingsBytes, TestElevationComparator, TestStressIndexing, TestAllFilesHaveChecksumFooter, TestSpansEnum, TestWeakIdentityMap, TestFlex, TestIntRangeFieldQueries, TestSentinelIntSet, TestDocIDMerger, TestSegmentMerger, TestNumericDocValuesUpdates, TestIOUtils, TestCharArraySet, TestLucene50StoredFieldsFormatHighCompression, TestControlledRealTimeReopenThread, TestDisjunctionMaxQuery, TestStressNRT, TestPerSegmentDeletes, TestTopFieldCollectorEarlyTermination, TestNRTCachingDirectory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNRTCachingDirectory -Dtests.seed=EA20D4E7B8F2C2BD -Dtests.slow=true -Dtests.locale=ar-LB -Dtests.timezone=Jamaica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestNRTCachingDirectory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestNRTCachingDirectory_EA20D4E7B8F2C2BD-001\testSeekPastEOF-001
[junit4] > at __randomizedtesting.SeedInfo.seed([EA20D4E7B8F2C2BD]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [190/466 (1!)] on J1 in 1.33s, 44 tests, 1 error, 2 skipped <<< FAILURES!
[...truncated 804 lines...]
[junit4] Suite: org.apache.lucene.store.TestMmapDirectory
[junit4] 2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=true): {}, locale=sr-Latn, timezone=America/Rainy_River
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=290465808,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestAutomatonQueryUnicode, TestSimilarity, Test2BSortedDocValuesFixedSorted, TestDocValuesScoring, TestNotDocIdSet, TestLogMergePolicy, TestOrdinalMap, TestForTooMuchCloning, TestTransactionRollback, TestIntsRef, TestPerFieldPostingsFormat, TestDirectory, TestEarlyTermination, TestPerFieldDocValuesFormat, TestBlockPostingsFormat3, TestFieldReuse, TestTermsEnum, TestLongPostings, TestSortRandom, TestTermRangeQuery, TestMaxTermFrequency, TestReadOnlyIndex, TestOfflineSorter, TestMultiFields, TestBasics, TestRollingUpdates, TestGeoUtils, TestLongBitSet, TestSpanOrQuery, Test4GBStoredFields, TestIntArrayDocIdSet, TestPostingsOffsets, TestConstantScoreQuery, TestBytesStore, TestGraphTokenizers, TestTermContext, TestPagedBytes, TestMultiTermsEnum, TestSingleInstanceLockFactory, TestCachingCollector, TestPerFieldPostingsFormat2, TestDuelingCodecsAtNight, TestFieldMaskingSpanQuery, TestIndexWriterDelete, TestLucene50CompoundFormat, TestNativeFSLockFactory, TestMergeSchedulerExternal, TestMultiDocValues, TestMultiset, TestFixedBitDocIdSet, TestOmitNorms, TestFilterDirectory, TestFutureObjects, TestMultiTermQueryRewrites, TestReaderClosed, Test2BPositions, TestIndexWriterLockRelease, TestDeterminism, TestStressDeletes, TestStopFilter, TestPackedInts, TestIndexWriterForceMerge, TestCharsRefBuilder, TestIntroSorter, TestDocumentsWriterStallControl, TestPayloads, TestBoostQuery, TestSearchAfter, TestSpanCollection, TestRadixSelector, TestStressAdvance, TestConcurrentMergeScheduler, TestNoMergeScheduler, TestSparseFixedBitDocIdSet, TestSpanFirstQuery, TestTermVectorsReader, TestIndexOrDocValuesQuery, TestIndexWriterMaxDocs, TestMathUtil, TestAxiomaticSimilarity, TestSynonymQuery, TestMultiCollector, TestAllFilesCheckIndexHeader, TestExitableDirectoryReader, Test2BPostingsBytes, TestElevationComparator, TestStressIndexing, TestAllFilesHaveChecksumFooter, TestSpansEnum, TestWeakIdentityMap, TestFlex, TestIntRangeFieldQueries, TestSentinelIntSet, TestDocIDMerger, TestSegmentMerger, TestNumericDocValuesUpdates, TestIOUtils, TestCharArraySet, TestLucene50StoredFieldsFormatHighCompression, TestControlledRealTimeReopenThread, TestDisjunctionMaxQuery, TestStressNRT, TestPerSegmentDeletes, TestTopFieldCollectorEarlyTermination, TestNRTCachingDirectory, TestSegmentInfos, TestDirectoryReaderReopen, TestCachingTokenFilter, TestTerms, TestIndexWriterOutOfFileDescriptors, TestFileSwitchDirectory, TestSloppyPhraseQuery2, TestTermsEnum2, TestDoubleRange, TestIndexWriterOnDiskFull, TestIndexWriterReader, TestIndexWriterWithThreads, TestAddIndexes, TestShardSearching, TestMultiMMap, TestSearcherManager, TestDeletionPolicy, TestDuelingCodecs, TestPersistentSnapshotDeletionPolicy, TestAtomicUpdate, TestStandardAnalyzer, TestSearchWithThreads, TestSnapshotDeletionPolicy, TestArrayUtil, TestBufferedIndexInput, TestConsistentFieldNumbers, TestSimpleExplanations, TestLockFactory, TestBagOfPositions, TestFastDecompressionMode, TestFieldCacheRewriteMethod, TestTransactions, TestMultiThreadTermVectors, TestSegmentTermDocs, TestSort, TestToken, TestFieldsReader, TestSimpleSearchEquivalence, TestCustomSearcherSort, TestSimilarityBase, TestBooleanMinShouldMatch, Test2BDocs, TestUTF32ToUTF8, TestStressIndexing2, TestTermVectorsWriter, TestCharTermAttributeImpl, TestBytesRefHash, TestOmitTf, TestDocumentWriter, TestMultiTermConstantScore, TestLiveFieldValues, TestFuzzyQuery, TestNoDeletionPolicy, TestSegmentTermEnum, TestUniqueTermCount, TestPrefixCodedTerms, TestAutomatonQuery, TestPrefixRandom, TestMultiLevelSkipList, TestSpanMultiTermQueryWrapper, TestBooleanQueryVisitSubscorers, TestIndexFileDeleter, TestParallelTermEnum, TestBinaryTerms, TestSubScorerFreqs, TestRecyclingIntBlockAllocator, TestConjunctions, TestField, TestSpanExplanationsOfNonMatches, TestSmallFloat, TestSetOnce, TestDateTools, TestPriorityQueue, TestTotalHitCountCollector, TestBooleanScorer, TestRollback, TestTwoPhaseCommitTool, TestCloseableThreadLocal, TestVersion, TestNGramPhraseQuery, TestIndexCommit, Test2BNumericDocValues, Test2BPagedBytes, TestDelegatingAnalyzerWrapper, TestWordlistLoader, TestBlockPostingsFormat, TestLucene60FieldInfoFormat, TestIndexedDISI, TestLucene70NormsFormat, TestFloatRange, TestLongRange, TestGeoEncodingUtils, TestPolygon2D, TestAllFilesDetectTruncation, TestCustomTermFreq, TestDocInverterPerFieldErrorInfo, TestDocValues, TestDocsWithFieldSet, TestFieldInvertState, TestFilterDirectoryReader, TestIndexManyDocuments, TestIndexReaderClose, TestIndexSorting, TestIndexTooManyDocs, TestIndexWriterExceptions2, TestIndexWriterThreadsToSegments, TestIndexingSequenceNumbers, TestInfoStream, TestManyFields, TestMaxPosition, TestMergePolicyWrapper, TestMergeRateLimiter, TestOneMergeWrappingMergePolicy, TestPointValues, TestReaderWrapperDVTypeCheck, TestTermVectors, TestTragicIndexWriterDeadlock, TestTryDelete, TestUpgradeIndexMergePolicy, FuzzyTermOnShortTermsTest, TermInSetQueryTest, TestApproximationSearchEquivalence, TestBlendedTermQuery, TestBoolean2ScorerSupplier, TestDocValuesRewriteMethod, TestDoubleValuesSource, TestFilterWeight, TestIndexSearcher, TestLRUQueryCache, TestLongRangeFieldQueries, TestMatchNoDocsQuery, TestNeedsScores, TestNormsFieldExistsQuery, TestQueryRescorer, TestReqExclBulkScorer, TestSegmentCacheables, TestSimpleExplanationsWithFillerDocs, TestClassicSimilarity, TestSpanBoostQuery, TestSpanNearQuery, TestMmapDirectory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestMmapDirectory -Dtests.seed=EA20D4E7B8F2C2BD -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=America/Rainy_River -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestMmapDirectory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J1\temp\lucene.store.TestMmapDirectory_EA20D4E7B8F2C2BD-001\testChecksum-001
[junit4] > at __randomizedtesting.SeedInfo.seed([EA20D4E7B8F2C2BD]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [442/466 (2!)] on J1 in 2.47s, 43 tests, 1 error, 1 skipped <<< FAILURES!
[...truncated 11162 lines...]
[junit4] Suite: org.apache.solr.servlet.DirectSolrConnectionTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001
[junit4] 2> 883949 WARN (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 883950 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 883954 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 883954 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 883955 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 883956 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 884029 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 884054 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 884064 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 884250 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c5f750
[junit4] 2> 884267 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c5f750
[junit4] 2> 884267 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c5f750
[junit4] 2> 884270 INFO (coreLoadExecutor-3154-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 884271 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 884347 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 884370 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 884381 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 884381 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 884381 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@c5f750
[junit4] 2> 884381 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 884381 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\]
[junit4] 2> 884387 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=6.8505859375, floorSegmentMB=1.6083984375, forceMergeDeletesPctAllowed=23.13585968959207, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5496393973418734
[junit4] 2> 884390 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=60
[junit4] 2> 884509 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 884509 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 884511 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=46, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=20.0771484375, floorSegmentMB=1.7666015625, forceMergeDeletesPctAllowed=5.682156541413056, segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 884511 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a1046d[collection1] main]
[junit4] 2> 884516 WARN (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 884517 INFO (coreLoadExecutor-3154-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 884519 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 884525 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInsertThenSelect
[junit4] 2> 884526 INFO (searcherExecutor-3155-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a1046d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 884526 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={stream.body=<delete><id>42</id></delete>}{delete=[42]} 0 0
[junit4] 2> 884528 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={stream.body=<add><doc><field+name%3D"id">42</field><field+name%3D"subject">Kittens!!!+?</field></doc></add>}{add=[42]} 0 1
[junit4] 2> 884528 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 884528 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@172985a commitCommandVersion:0
[junit4] 2> 884556 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@109aa80[collection1] main]
[junit4] 2> 884556 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 884558 INFO (searcherExecutor-3155-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@109aa80[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C1)))}
[junit4] 2> 884559 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={stream.body=<commit/>}{commit=} 0 30
[junit4] 2> 884559 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:42&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 884560 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[42]} 0 0
[junit4] 2> 884560 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[42]} 0 0
[junit4] 2> 884561 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 884561 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@172985a commitCommandVersion:0
[junit4] 2> 884582 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ef9287[collection1] main]
[junit4] 2> 884583 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 884587 INFO (searcherExecutor-3155-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ef9287[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.3.0):C1)))}
[junit4] 2> 884589 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 28
[junit4] 2> 884590 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:42&wt=xml} hits=1 status=0 QTime=0
[junit4] 2> 884590 INFO (TEST-DirectSolrConnectionTest.testInsertThenSelect-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testInsertThenSelect
[junit4] 2> 884593 INFO (TEST-DirectSolrConnectionTest.testSimpleRequest-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSimpleRequest
[junit4] 2> 884593 INFO (TEST-DirectSolrConnectionTest.testSimpleRequest-seed#[C64A56B619D0439D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&echoParams=explicit&wt=xml&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 884593 INFO (TEST-DirectSolrConnectionTest.testSimpleRequest-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSimpleRequest
[junit4] 2> 884594 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 884594 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=28114932
[junit4] 2> 884594 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 884594 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1716cce: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@11405c
[junit4] 2> 884598 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 884598 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b8409: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1a6fd3b
[junit4] 2> 884604 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 884605 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@48a697: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1996f97
[junit4] 2> 884605 INFO (coreCloseExecutor-3159-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@18c9ae4
[junit4] 2> 884605 INFO (coreCloseExecutor-3159-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=25991908
[junit4] 2> 884606 INFO (coreCloseExecutor-3159-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11bc084: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@13d7f70
[junit4] 2> 884618 INFO (SUITE-DirectSolrConnectionTest-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {subject=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=903, maxMBSortInHeap=6.686147740032025, sim=RandomSimilarity(queryNorm=false): {}, locale=es-AR, timezone=America/Santo_Domingo
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=105284888,total=208863232
[junit4] 2> NOTE: All tests run in this JVM: [TestRestManager, TemplateUpdateProcessorTest, TestSortByMinMaxFunction, TestCoreContainer, DistributedVersionInfoTest, CloneFieldUpdateProcessorFactoryTest, InfixSuggestersTest, TestSlowCompositeReaderWrapper, CustomCollectionTest, OverseerRolesTest, DistribCursorPagingTest, PingRequestHandlerTest, MetricsConfigTest, SynonymTokenizerTest, TestHighFrequencyDictionaryFactory, WordBreakSolrSpellCheckerTest, TestInPlaceUpdatesDistrib, TestHdfsBackupRestoreCore, ZkCLITest, TestSkipOverseerOperations, MissingSegmentRecoveryTest, FileUtilsTest, TestConfigSetsAPIZkFailure, SpellingQueryConverterTest, FastVectorHighlighterTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestFastOutputStream, HdfsUnloadDistributedZkTest, ConjunctionSolrSpellCheckerTest, TestSSLRandomization, AutoScalingHandlerTest, TestCloudSearcherWarming, TestCollectionAPIs, CoreAdminCreateDiscoverTest, ReplicationFactorTest, HdfsBasicDistributedZkTest, TestConfigSetsAPI, TestSystemCollAutoCreate, TestTolerantSearch, UtilsToolTest, ConfigureRecoveryStrategyTest, MetricUtilsTest, TermsComponentTest, OverseerCollectionConfigSetProcessorTest, TestCustomSort, TestPhraseSuggestions, TestUtils, TestPolicyCloud, TestLockTree, SuggestComponentContextFilterQueryTest, HttpSolrCallGetCoreTest, TestHdfsCloudBackupRestore, DateRangeFieldTest, SolrIndexConfigTest, HdfsThreadLeakTest, SmileWriterTest, TestDFISimilarityFactory, DeleteShardTest, LegacyCloudClusterPropTest, HdfsChaosMonkeySafeLeaderTest, TestJsonFacetRefinement, LeaderElectionIntegrationTest, TestStressCloudBlindAtomicUpdates, DistributedFacetPivotSmallTest, TestTrie, TestFieldResource, TestSolrDeletionPolicy1, SampleTest, CollectionsAPIDistributedZkTest, TestLRUStatsCache, CoreMergeIndexesAdminHandlerTest, TestSchemaResource, TestStandardQParsers, SolrMetricsIntegrationTest, TestCloudNestedDocsSort, TestReload, SearchHandlerTest, TestCollectionAPI, TestFieldTypeResource, DirectSolrConnectionTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DirectSolrConnectionTest -Dtests.seed=C64A56B619D0439D -Dtests.slow=true -Dtests.locale=es-AR -Dtests.timezone=America/Santo_Domingo -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J0 | DirectSolrConnectionTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001\index
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.servlet.DirectSolrConnectionTest_C64A56B619D0439D-001\init-core-data-001
[junit4] > at __randomizedtesting.SeedInfo.seed([C64A56B619D0439D]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [223/777 (1!)] on J0 in 0.70s, 2 tests, 1 error <<< FAILURES!
[...truncated 1665 lines...]
[junit4] Suite: org.apache.solr.search.TestSearcherReuse
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001
[junit4] 2> 3723159 WARN (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 3723160 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 3723164 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 3723165 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3723172 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 3723196 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 3723207 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema-id-and-version-fields-only.xml instead
[junit4] 2> 3723211 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=id-and-version-fields-only
[junit4] 2> 3723214 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.IndexSchema Loaded schema id-and-version-fields-only/1.6 with uniqueid field id
[junit4] 2> 3723216 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 3723218 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema, renamed the non-managed schema C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1\conf\schema-id-and-version-fields-only.xml to C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1\conf\schema-id-and-version-fields-only.xml.bak
[junit4] 2> 3723303 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@df0624
[junit4] 2> 3723313 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@df0624
[junit4] 2> 3723313 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@df0624
[junit4] 2> 3723319 INFO (coreLoadExecutor-15047-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3723365 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 3723374 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=id-and-version-fields-only
[junit4] 2> 3723378 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema id-and-version-fields-only/1.6 with uniqueid field id
[junit4] 2> 3723378 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1, trusted=true
[junit4] 2> 3723379 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@df0624
[junit4] 2> 3723379 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 3723379 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\]
[junit4] 2> 3723386 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=44, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=43.244140625, floorSegmentMB=1.6650390625, forceMergeDeletesPctAllowed=26.710454319521187, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.28620297437992814
[junit4] 2> 3723394 WARN (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 3723470 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 3723470 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 3723471 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 3723471 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 3723474 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=14, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6806972919551673]
[junit4] 2> 3723474 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c8ff1f[collection1] main]
[junit4] 2> 3723475 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1\conf
[junit4] 2> 3723476 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 3723476 INFO (coreLoadExecutor-15047-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1593145778366316544
[junit4] 2> 3723482 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 3723489 INFO (searcherExecutor-15048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c8ff1f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3723491 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 3723493 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1 (1593145778383093760)]} 0 1
[junit4] 2> 3723499 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778390433792,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723499 WARN (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the entire index! Use with care.
[junit4] 2> 3723509 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18b125 commitCommandVersion:1593145778390433792
[junit4] 2> 3723516 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5ee848[collection1] main]
[junit4] 2> 3723517 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723518 INFO (searcherExecutor-15048-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5ee848[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.3.0):c1)))}
[junit4] 2> 3723518 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{optimize=} 0 19
[junit4] 2> 3723519 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=*:*&qt=&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 3723520 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778412453888,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723520 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 3723521 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723521 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 3723521 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778413502464,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723522 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 3723522 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723522 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 3723522 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778414551040,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 3723523 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723523 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 3723523 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778415599616,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 3723523 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723524 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 3723527 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=id:match_no_documents (-1593145778416648192)} 0 2
[junit4] 2> 3723528 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778420842496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 3723528 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723529 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 0
[junit4] 2> 3723529 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[0 (-1593145778421891072)]} 0 0
[junit4] 2> 3723537 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778430279680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 3723537 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723538 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 1
[junit4] 2> 3723538 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{delete=[1 (-1593145778431328256)]} 0 0
[junit4] 2> 3723538 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778431328257,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723538 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18b125 commitCommandVersion:1593145778431328257
[junit4] 2> 3723542 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@b99d1b[collection1] main]
[junit4] 2> 3723543 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723544 INFO (searcherExecutor-15048-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b99d1b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3723545 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 3723548 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0 (1593145778439716864)]} 0 1
[junit4] 2> 3723548 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778441814016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723548 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18b125 commitCommandVersion:1593145778441814016
[junit4] 2> 3723556 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@23ec98[collection1] main]
[junit4] 2> 3723556 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723558 INFO (searcherExecutor-15048-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23ec98[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.3.0):c1)))}
[junit4] 2> 3723558 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 3723561 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@67a7af[collection1] realtime]
[junit4] 2> 3723562 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=id:[0 TO 5] (-1593145778453348352)} 0 3
[junit4] 2> 3723562 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778456494080,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723563 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@18b125 commitCommandVersion:1593145778456494080
[junit4] 2> 3723565 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c1644a[collection1] main]
[junit4] 2> 3723566 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723567 INFO (searcherExecutor-15048-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c1644a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3723568 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 3723573 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.ManagedIndexSchema Upgraded to managed schema at C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\tempDir-001\collection1\conf\managed-schema
[junit4] 2> 3723574 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778469076992,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 3723575 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1604b04[collection1] main]
[junit4] 2> 3723576 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723577 INFO (searcherExecutor-15048-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1604b04[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 3723577 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 2
[junit4] 2> 3723578 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 3723580 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:* (-1593145778473271296)} 0 1
[junit4] 2> 3723580 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1593145778475368448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 3723581 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 3723582 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 3723582 INFO (TEST-TestSearcherReuse.test-seed#[C64A56B619D0439D]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 1
[junit4] 2> 3723583 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 3723583 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13927461
[junit4] 2> 3723583 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 3723583 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@24c7c: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@13d8de8
[junit4] 2> 3723588 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 3723588 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@f4167d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@17c4e3a
[junit4] 2> 3723594 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 3723594 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1cc3974: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@149ddcb
[junit4] 2> 3723602 INFO (coreCloseExecutor-15053-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@16aa324
[junit4] 2> 3723603 INFO (coreCloseExecutor-15053-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=23765796
[junit4] 2> 3723603 INFO (coreCloseExecutor-15053-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@8316ca: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@12de0f0
[junit4] 2> 3723613 INFO (SUITE-TestSearcherReuse-seed#[C64A56B619D0439D]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=540, maxMBSortInHeap=5.69826115392473, sim=RandomSimilarity(queryNorm=false): {}, locale=ru, timezone=Africa/Harare
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=272769744,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestHashPartitioner, TestExtendedDismaxParser, SolrCmdDistributorTest, TestRTGBase, FacetPivotSmallTest, TestGraphMLResponseWriter, TestLegacyFieldCache, TestSolrDeletionPolicy2, ZkSolrClientTest, JvmMetricsTest, HdfsCollectionsAPIDistributedZkTest, TestSolrCloudWithSecureImpersonation, UpdateRequestProcessorFactoryTest, TermVectorComponentTest, TestManagedSchema, TestNumericTerms64, RequestHandlersTest, SchemaApiFailureTest, SuggesterWFSTTest, TestComponentsName, TestUseDocValuesAsStored2, OverseerModifyCollectionTest, TestSimpleQParserPlugin, TestAtomicUpdateErrorCases, TestCloudPivotFacet, TestRequestStatusCollectionAPI, TestWriterPerf, TestOrdValues, TestSolrQueryResponse, CoreAdminRequestStatusTest, TestPushWriter, TestSegmentSorting, ZkControllerTest, TestLeaderInitiatedRecoveryThread, TestInPlaceUpdatesStandalone, HdfsAutoAddReplicasIntegrationTest, BaseCdcrDistributedZkTest, TestStressInPlaceUpdates, TestSchemaNameResource, TestApiFramework, TestFieldCollectionResource, TestSha256AuthenticationProvider, DocExpirationUpdateProcessorFactoryTest, TestConfigsApi, DistributedFacetSimpleRefinementLongTailTest, TestSimDistributedQueue, XsltUpdateRequestHandlerTest, SimpleFacetsTest, TestUnifiedSolrHighlighter, TestFieldCache, OverseerStatusTest, TestStressUserVersions, TestSchemaSimilarityResource, DirectUpdateHandlerTest, TestPayloadScoreQParserPlugin, ClusterStateUpdateTest, TestReloadDeadlock, TestFieldTypeCollectionResource, DistanceUnitsTest, TestCorePropertiesReload, TestStressLucene, ManagedSchemaRoundRobinCloudTest, CheckHdfsIndexTest, CollectionStateFormat2Test, PrimUtilsTest, TestLuceneMatchVersion, PeerSyncWithIndexFingerprintCachingTest, TestStressVersions, TestJettySolrRunner, SegmentsInfoRequestHandlerTest, TestLocalFSCloudBackupRestore, ConcurrentCreateRoutedAliasTest, DOMUtilTest, BadIndexSchemaTest, SimplePostToolTest, TestCollectionsAPIViaSolrCloudCluster, TestRandomFaceting, HdfsWriteToMultipleCollectionsTest, SpellCheckCollatorTest, TestConfig, CdcrReplicationHandlerTest, CollectionReloadTest, DisMaxRequestHandlerTest, QueryResultKeyTest, CSVRequestHandlerTest, ZkStateWriterTest, V2ApiIntegrationTest, HdfsTlogReplayBufferedWhileIndexingTest, QueryParsingTest, BufferStoreTest, AtomicUpdateProcessorFactoryTest, TestPivotHelperCode, DocValuesTest, TestUpdate, IndexSchemaTest, BadComponentTest, TestClusterStateMutator, TestReRankQParserPlugin, TestManagedSynonymGraphFilterFactory, UpdateParamsTest, ExternalFileFieldSortTest, TestInitParams, TestSubQueryTransformerDistrib, CoreSorterTest, TestNonDefinedSimilarityFactory, SolrJmxReporterCloudTest, BlockJoinFacetRandomTest, TestLeaderElectionWithEmptyReplica, TestReplicationHandlerBackup, TestCollapseQParserPlugin, SpellCheckComponentTest, TestLegacyFieldReuse, SolrCoreCheckLockOnStartupTest, TestMaxScoreQueryParser, ConnectionManagerTest, SolrSlf4jReporterTest, TestPrepRecovery, TolerantUpdateProcessorTest, UninvertDocValuesMergePolicyTest, TestComplexPhraseQParserPlugin, NotRequiredUniqueKeyTest, DistributedQueueTest, VMParamsZkACLAndCredentialsProvidersTest, BigEndianAscendingWordSerializerTest, TestIndexingPerformance, HdfsSyncSliceTest, MinimalSchemaTest, HLLSerializationTest, DebugComponentTest, TestSolrConfigHandlerConcurrent, DeleteStatusTest, NoCacheHeaderTest, TestSolr4Spatial, TestConfigSetProperties, TestWordDelimiterFilterFactory, TestExecutePlanAction, AddBlockUpdateTest, TestFastWriter, TestDistribDocBasedVersion, OverriddenZkACLAndCredentialsProvidersTest, TestExactStatsCache, TestDistributedSearch, ResponseLogComponentTest, ShardRoutingTest, TestFieldCacheSort, FieldAnalysisRequestHandlerTest, TestSolrCoreProperties, DeleteLastCustomShardedReplicaTest, CdcrVersionReplicationTest, ExitableDirectoryReaderTest, SharedFSAutoReplicaFailoverTest, DistributedFacetPivotSmallAdvancedTest, FullSolrCloudDistribCmdsTest, TestDownShardTolerantSearch, DefaultValueUpdateProcessorTest, TestSimpleTextCodec, CursorMarkTest, TestMaxTokenLenTokenizer, BJQParserTest, TestManagedStopFilterFactory, CoreAdminHandlerTest, LoggingHandlerTest, TestBlobHandler, CachingDirectoryFactoryTest, HdfsChaosMonkeyNothingIsSafeTest, ClassificationUpdateProcessorTest, ResponseHeaderTest, TestConfigOverlay, DistributedTermsComponentTest, TestDistributedGrouping, PropertiesRequestHandlerTest, RemoteQueryErrorTest, TestCloudInspectUtil, JavabinLoaderTest, ReplicaListTransformerTest, TestIntervalFaceting, TestReversedWildcardFilterFactory, DocValuesMultiTest, JSONWriterTest, TestLRUCache, VersionInfoTest, SuggestComponentTest, TestRequestForwarding, HttpPartitionOnCommitTest, SSLMigrationTest, DocValuesNotIndexedTest, TestBadConfig, TestImpersonationWithHadoopAuth, TestDirectoryFactory, HdfsRecoveryZkTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, OverseerTest, BasicZkTest, RecoveryZkTest, LeaderElectionTest, TestRandomDVFaceting, ShardRoutingCustomTest, TestFaceting, DistributedSpellCheckComponentTest, TestRealTimeGet, TestStressReorder, TestJoin, TestRangeQuery, StatsComponentTest, PeerSyncTest, TestSort, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, TestCoreDiscovery, SuggesterFSTTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, QueryEqualityTest, SortByFunctionTest, DistanceFunctionTest, SolrInfoBeanTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, FileBasedSpellCheckerTest, DocumentBuilderTest, TestValueSourceCache, MoreLikeThisHandlerTest, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, SolrPluginUtilsTest, ReturnFieldsTest, TestCSVResponseWriter, MBeansHandlerTest, TestAnalyzedSuggestions, JsonLoaderTest, TestPHPSerializedResponseWriter, TestLFUCache, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, TestStressRecovery, TestMergePolicyConfig, MultiTermTest, TestDocSet, TestSearchPerf, NumericFieldsTest, TestFuzzyAnalyzedSuggestions, SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, TestSystemIdResolver, TestSuggestSpellingConverter, DateFieldTest, RAMDirectoryFactoryTest, ClusterStateTest, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestHighlightDedupGrouping, TestSimpleTrackingShardHandler, TestLuceneIndexBackCompat, TestEmbeddedSolrServerAdminHandler, TestEmbeddedSolrServerConstructors, TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, ActionThrottleTest, AliasIntegrationTest, AsyncCallRequestStatusResponseTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, ConfigSetsAPITest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteNodeTest, DeleteReplicaTest, DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, ForceLeaderTest, LeaderFailoverAfterPartitionTest, LeaderFailureAfterFreshStartTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, MultiThreadedOCPTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, SolrCloudExampleTest, TestCloudPseudoReturnFields, TestClusterProperties, TestConfigSetsAPIExclusivity, TestCryptoKeys, TestDeleteCollectionOnDownNodes, TestExclusionRuleCollectionAccess, TestLeaderElectionZkExpiry, TestOnReconnectListenerSupport, TestPullReplicaErrorHandling, TestTolerantUpdateProcessorCloud, TestTolerantUpdateProcessorRandomCloud, TestUtilizeNode, TlogReplayBufferedWhileIndexingTest, TriLevelCompositeIdRoutingTest, ZkFailoverTest, ConcurrentDeleteAndCreateCollectionTest, SimpleCollectionCreateDeleteTest, TestReplicaProperties, ComputePlanActionTest, SearchRateTriggerTest, TestClusterStateProvider, TestComputePlanAction, TestGenericDistributedQueue, TestLargeCluster, TestNodeAddedTrigger, TestNodeLostTrigger, TestTriggerIntegration, CdcrBidirectionalTest, CdcrBootstrapTest, HDFSCollectionsAPITest, HdfsBasicDistributedZk2Test, HdfsNNFailoverTest, HdfsRecoverLeaseTest, StressHdfsTest, ImplicitSnitchTest, RuleEngineTest, RulesTest, BlobRepositoryCloudTest, DirectoryFactoryTest, HdfsDirectoryFactoryTest, TestBackupRepositoryFactory, TestConfigSetImmutable, TestConfigSets, TestDynamicLoading, TestImplicitCoreProperties, TestInfoStreamLogging, TestNRTOpen, TestReloadAndDeleteDocs, TestShardHandlerFactory, TestSolrConfigHandler, TestSolrXml, TestSolrCloudSnapshots, TestSolrCoreSnapshots, ResponseBuilderTest, TestConfigReload, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, V2StandaloneTest, AutoscalingHistoryHandlerTest, DistributedFacetPivotWhiteBoxTest, DistributedQueryComponentOptimizationTest, TestDistributedStatsComponentCardinality, TestExpandComponent, TestHttpShardHandlerFactory, TestLegacyField, TestLegacyTerms, TestMultiValuedNumericRangeQuery, TestNumericRangeQuery32, TestNumericRangeQuery64, TestNumericTokenStream, SolrCoreMetricManagerTest, SolrMetricReporterTest, SolrGangliaReporterTest, SolrJmxReporterTest, RegexBytesRefFilterTest, SubstringBytesRefFilterTest, TestStreamBody, TestMacroExpander, TestMacros, TestBinaryResponseWriter, TestCustomDocTransformer, TestExportWriter, TestGeoJSONResponseWriter, TestRawResponseWriter, TestRawTransformer, TestRetrieveFieldsOptimizer, TestSubQueryTransformer, TestSubQueryTransformerCrossCore, TestManagedResourceStorage, TestBulkSchemaAPI, TestSchemaVersionResource, TestSerializedLuceneMatchVersion, BooleanFieldTest, DocValuesMissingTest, SpatialRPTFieldTypeTest, TestCloudManagedSchema, TestCollationFieldDocValues, TestHalfAndHalfDocValues, TestManagedSchemaThreadSafety, TestPointFields, TestSchemaManager, TestSchemalessBufferedUpdates, TestSortableTextField, AnalyticsMergeStrategyTest, TestSearcherReuse]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSearcherReuse -Dtests.seed=C64A56B619D0439D -Dtests.slow=true -Dtests.locale=ru -Dtests.timezone=Africa/Harare -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | TestSearcherReuse (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001\init-core-data-001\index
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.search.TestSearcherReuse_C64A56B619D0439D-001
[junit4] > at __randomizedtesting.SeedInfo.seed([C64A56B619D0439D]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [715/777 (2!)] on J1 in 0.50s, 1 test, 1 error <<< FAILURES!
[...truncated 2596 lines...]
[junit4] Suite: org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\init-core-data-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1449, maxMBSortInHeap=5.626859563218241, sim=RandomSimilarity(queryNorm=false): {}, locale=be, timezone=America/Araguaina
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=3419648,total=23965696
[junit4] 2> NOTE: All tests run in this JVM: [TestICUCollationFieldOptions, TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory -Dtests.seed=7D5E4982A67B9CE7 -Dtests.slow=true -Dtests.locale=be -Dtests.timezone=America/Araguaina -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-sent.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001\tempDir-001\collection1\conf\en-test-ner-person.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_7D5E4982A67B9CE7-001
[junit4] > at __randomizedtesting.SeedInfo.seed([7D5E4982A67B9CE7]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [3/5 (1!)] on J1 in 1.47s, 8 tests, 1 error <<< FAILURES!
[...truncated 43385 lines...]
[JENKINS] Lucene-Solr-7.x-Windows (32bit/jdk1.8.0_144) - Build #
472 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/472/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseSerialGC
5 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.EchoParamsTest
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]: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.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue
Error Message:
action wasn't interrupted
Stack Trace:
java.lang.AssertionError: action wasn't interrupted
at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2:6A990BAA38A63737]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testEventQueue(TriggerIntegrationTest.java:726)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at 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.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.schema.TestBulkSchemaAPI
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.rest.schema.TestBulkSchemaAPI: 1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.rest.schema.TestBulkSchemaAPI:
1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.rest.schema.TestBulkSchemaAPI
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
Error Message:
Could not remove the following files (in the order of attempts): C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001
Stack Trace:
java.io.IOException: Could not remove the following files (in the order of attempts):
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001
at __randomizedtesting.SeedInfo.seed([1C7690B804FAC724]: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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12131 lines...]
[junit4] Suite: org.apache.solr.EchoParamsTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] 2> 21811 WARN (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 21811 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 21818 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 21819 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 21819 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 21820 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 21898 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 21916 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 21922 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 22015 WARN (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX.
[junit4] 2> 22015 WARN (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX.
[junit4] 2> 22015 WARN (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX.
[junit4] 2> 22018 INFO (coreLoadExecutor-57-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 22018 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 22090 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
[junit4] 2> 22103 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 22108 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 22108 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 22109 WARN (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via JMX.
[junit4] 2> 22109 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 22109 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001\]
[junit4] 2> 22113 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=31, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=99.5078125, floorSegmentMB=1.5859375, forceMergeDeletesPctAllowed=20.633072445564178, segmentsPerTier=12.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 22146 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=60
[junit4] 2> 22227 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22227 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22229 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7040324949807278]
[junit4] 2> 22229 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@b452fa[collection1] main]
[junit4] 2> 22230 WARN (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
[junit4] 2> 22231 INFO (coreLoadExecutor-57-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 22232 INFO (searcherExecutor-58-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@b452fa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 22236 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 22238 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 22253 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=15
[junit4] 2> 22260 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22260 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&start=0&rows=20&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22261 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&start=0&rows=20&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22263 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 22263 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 22264 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=foo&qt=&echoParams=explicit&start=0&rows=20&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 22265 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={echoHandler=true&q=foo&qt=/crazy_custom_qt&echoParams=all&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22266 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={echoHandler=true&q=foo&qt=/crazy_custom_qt&echoParams=all&start=0&rows=20&version=2.2&wt=xml} hits=0 status=0 QTime=0
[junit4] 2> 22266 INFO (TEST-EchoParamsTest.test-seed#[A32C490431C1F1C2]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 22266 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 22266 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=427348
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3cfbc: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / null
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5c3dd9: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / null
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 22267 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@52bcc5: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / null
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ac999b
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=28088731
[junit4] 2> 22268 INFO (coreCloseExecutor-62-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@11cb92c: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / null
[junit4] 2> 22271 INFO (SUITE-EchoParamsTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=nn-NO, timezone=NZ
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=19361856,total=51142656
[junit4] 2> NOTE: All tests run in this JVM: [DocExpirationUpdateProcessorFactoryTest, TestUseDocValuesAsStored2, TestManagedResourceStorage, TestDynamicFieldCollectionResource, ActionThrottleTest, ExternalFileFieldSortTest, EchoParamsTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=EchoParamsTest -Dtests.seed=A32C490431C1F1C2 -Dtests.slow=true -Dtests.locale=nn-NO -Dtests.timezone=NZ -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | EchoParamsTest (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.EchoParamsTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] > at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [8/777 (1!)] on J1 in 0.49s, 1 test, 1 error <<< FAILURES!
[...truncated 471 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\init-core-data-001
[junit4] 2> 330158 WARN (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
[junit4] 2> 330158 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 330162 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 330162 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 330164 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001
[junit4] 2> 330164 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 330164 INFO (Thread-642) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 330164 INFO (Thread-642) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 330168 ERROR (Thread-642) [ ] 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> 330264 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:58093
[junit4] 2> 330270 INFO (zkConnectionManagerCallback-646-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330274 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100af03f59d0000, likely client has closed socket
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 330277 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@fc5dea{/solr,null,AVAILABLE}
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 330279 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1d21846{/solr,null,AVAILABLE}
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1ebd725{HTTP/1.1,[http/1.1]}{127.0.0.1:58100}
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ] o.e.j.s.Server Started @337724ms
[junit4] 2> 330284 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58100}
[junit4] 2> 330285 ERROR (jetty-launcher-643-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T23:20:40.472Z
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b20c3{HTTP/1.1,[http/1.1]}{127.0.0.1:58097}
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.e.j.s.Server Started @337725ms
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58097}
[junit4] 2> 330285 ERROR (jetty-launcher-643-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 330285 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T23:20:40.472Z
[junit4] 2> 330291 INFO (zkConnectionManagerCallback-648-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330292 INFO (zkConnectionManagerCallback-650-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330293 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 330293 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 330306 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100af03f59d0002, likely client has closed socket
[junit4] 2> 330312 INFO (jetty-launcher-643-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 330498 INFO (zkConnectionManagerCallback-654-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330503 INFO (jetty-launcher-643-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 330505 INFO (zkConnectionManagerCallback-656-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330510 INFO (zkConnectionManagerCallback-660-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330513 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100af03f59d0005, likely client has closed socket
[junit4] 2> 330529 INFO (zkConnectionManagerCallback-662-thread-1-processing-n:127.0.0.1:58097_solr) [n:127.0.0.1:58097_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 330768 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 330768 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 330769 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58097_solr
[junit4] 2> 330770 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58100_solr
[junit4] 2> 330771 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer (id=72250025578397702-127.0.0.1:58097_solr-n_0000000000) starting
[junit4] 2> 330788 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58097_solr
[junit4] 2> 330972 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 330980 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 330980 INFO (zkCallback-655-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 330981 INFO (zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr) [n:127.0.0.1:58097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 330982 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58100_solr, 127.0.0.1:58097_solr]
[junit4] 2> 330982 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 330982 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 330989 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 330993 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 331062 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58097.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331071 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58097.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331071 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58097.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331079 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58100.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331093 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58100.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331093 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58100.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 331094 INFO (jetty-launcher-643-thread-2) [n:127.0.0.1:58097_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node2\.
[junit4] 2> 331096 INFO (jetty-launcher-643-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node1\.
[junit4] 2> 331098 DEBUG (ScheduledTrigger-1494-thread-1) [n:127.0.0.1:58097_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2
[junit4] 2> 331424 INFO (zkConnectionManagerCallback-668-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331433 INFO (zkConnectionManagerCallback-672-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 331434 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 331435 INFO (SUITE-TriggerIntegrationTest-seed#[A32C490431C1F1C2]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58093/solr ready
[junit4] 2> 331452 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testTriggerThrottling
[junit4] 2> 331456 INFO (qtp5089530-3036) [n:127.0.0.1:58097_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :overseerstatus with params action=OVERSEERSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 331473 INFO (qtp5089530-3036) [n:127.0.0.1:58097_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=OVERSEERSTATUS&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 331475 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.a.ScheduledTriggers -- removing trigger: .auto_add_replicas
[junit4] 2> 331481 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 331484 DEBUG (zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 331484 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 331484 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 331485 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 331485 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 331485 DEBUG (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 331487 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5b20c3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 331488 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20519641
[junit4] 2> 331489 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 331489 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a4a5e9: rootName = solr_58097, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@e76f1f
[junit4] 2> 331494 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 331494 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@19f7f7b: rootName = solr_58097, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@5e1a7b
[junit4] 2> 331498 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 331498 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4bc6a9: rootName = solr_58097, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@1c11675
[junit4] 2> 331500 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:58097_solr
[junit4] 2> 331503 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 331503 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 331504 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.Overseer Overseer (id=72250025578397702-127.0.0.1:58097_solr-n_0000000000) closing
[junit4] 2> 331505 INFO (OverseerStateUpdate-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:58097_solr
[junit4] 2> 331508 DEBUG (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 331509 WARN (OverseerAutoScalingTriggerThread-72250025578397702-127.0.0.1:58097_solr-n_0000000000) [n:127.0.0.1:58097_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 331512 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100af03f59d0006, likely client has closed socket
[junit4] 2> 331512 INFO (zkCallback-661-thread-1-processing-n:127.0.0.1:58097_solr) [n:127.0.0.1:58097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331512 INFO (zkCallback-655-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331513 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@fc5dea{/solr,null,UNAVAILABLE}
[junit4] 2> 331513 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 331513 INFO (zkCallback-671-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331514 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 331515 INFO (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58100_solr
[junit4] 2> 331515 INFO (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.Overseer Overseer (id=72250025578397700-127.0.0.1:58100_solr-n_0000000001) starting
[junit4] 2> 331525 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 331527 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 331527 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58100_solr]
[junit4] 2> 331527 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion -1
[junit4] 2> 331528 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 331529 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 331531 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 331531 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 332533 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 333534 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 334536 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 335537 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 336517 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 336519 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 336519 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 336519 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 336521 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c341b0{/solr,null,AVAILABLE}
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@17ab75a{HTTP/1.1,[http/1.1]}{127.0.0.1:58211}
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.Server Started @343962ms
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58211}
[junit4] 2> 336522 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 336522 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T23:20:46.709Z
[junit4] 2> 336527 INFO (zkConnectionManagerCallback-675-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336528 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 336544 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1
[junit4] 2> 336549 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 336551 INFO (zkConnectionManagerCallback-679-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336556 INFO (zkConnectionManagerCallback-681-thread-1-processing-n:127.0.0.1:58211_solr) [n:127.0.0.1:58211_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336565 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 336566 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 336572 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58211_solr
[junit4] 2> 336575 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336575 INFO (zkCallback-671-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336575 INFO (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336576 INFO (zkCallback-680-thread-1-processing-n:127.0.0.1:58211_solr) [n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 336717 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58211.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336735 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58211.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336735 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58211.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 336738 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58211_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node3\.
[junit4] 2> 336790 INFO (qtp24431827-3040) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 336794 INFO (qtp4875339-3109) [n:127.0.0.1:58211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 336795 DEBUG (qtp24431827-3045) [n:127.0.0.1:58100_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 336796 INFO (qtp24431827-3045) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 336796 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 336797 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336798 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336798 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 336798 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 336801 INFO (qtp24431827-3040) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 336803 INFO (qtp4875339-3111) [n:127.0.0.1:58211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 336804 DEBUG (qtp24431827-3042) [n:127.0.0.1:58100_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 336804 INFO (qtp24431827-3042) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 336804 INFO (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 336805 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 336805 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336805 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 336805 DEBUG (ScheduledTrigger-1503-thread-3) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 336805 DEBUG (ScheduledTrigger-1503-thread-3) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 336805 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336806 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr]
[junit4] 2> 336806 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 336806 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 4
[junit4] 2> 336806 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 336807 INFO (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 336807 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 336807 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 336807 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 336807 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 336807 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 336807 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 2
[junit4] 2> 336807 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@19c7009{/solr,null,AVAILABLE}
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@15fef79{HTTP/1.1,[http/1.1]}{127.0.0.1:58226}
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.e.j.s.Server Started @344251ms
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58226}
[junit4] 2> 336811 ERROR (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 336811 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-02-23T23:20:46.998Z
[junit4] 2> 336814 INFO (zkConnectionManagerCallback-685-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336815 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 336827 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x100af03f59d000c, likely client has closed socket
[junit4] 2> 336831 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58093/solr
[junit4] 2> 336833 INFO (zkConnectionManagerCallback-689-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336837 INFO (zkConnectionManagerCallback-691-thread-1-processing-n:127.0.0.1:58226_solr) [n:127.0.0.1:58226_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 336847 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 336850 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 336854 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58226_solr
[junit4] 2> 336856 INFO (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336856 INFO (zkCallback-671-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336857 INFO (zkCallback-680-thread-1-processing-n:127.0.0.1:58211_solr) [n:127.0.0.1:58211_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336857 INFO (zkCallback-671-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 336858 INFO (zkCallback-690-thread-1-processing-n:127.0.0.1:58226_solr) [n:127.0.0.1:58226_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 337219 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58226.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337229 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58226.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337229 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58226.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15ce9fc
[junit4] 2> 337231 INFO (TEST-TriggerIntegrationTest.testTriggerThrottling-seed#[A32C490431C1F1C2]) [n:127.0.0.1:58226_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.TriggerIntegrationTest_A32C490431C1F1C2-001\tempDir-001\node4\.
[junit4] 2> 337546 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 337808 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 337809 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:58226_solr at time 2936279930489039
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 firing registered processor for nodes: [127.0.0.1:58226_solr] added at times [2936279930489039], now=2936279930538248
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":2936279930489039,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930489039],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337812 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:58226_solr at time 2936279930504399
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:58226_solr] added at times [2936279930504399], now=2936279931407789
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz96",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337813 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_trigger2, node_added_trigger1]
[junit4] 2> 337813 INFO (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 337815 INFO (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 337819 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95",
[junit4] 2> "source":"node_added_trigger2",
[junit4] 2> "eventTime":2936279930489039,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930489039],
[junit4] 2> "_enqueue_time_":2936279931752249,
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 337819 INFO (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger2
[junit4] 2> 337822 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 5000ms
[junit4] 2> 337822 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms
[junit4] 2> 337822 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms
[junit4] 2> 337822 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 4 ms for event id=a6e87f237c8cfT732ukvjnyoqcuvvfgi0hwiz95
[junit4] 2> 342823 DEBUG (ScheduledTrigger-1503-thread-1) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger1
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger2
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-2) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 3
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 firing registered processor for nodes: [127.0.0.1:58226_solr] added at times [2936279930504399], now=2936284942598901
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 342824 DEBUG (ScheduledTrigger-1503-thread-4) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [.auto_add_replicas, node_added_trigger2, node_added_trigger1]
[junit4] 2> 342825 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99",
[junit4] 2> "source":"node_added_trigger1",
[junit4] 2> "eventTime":2936279930504399,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[2936279930504399],
[junit4] 2> "_enqueue_time_":2936284942962419,
[junit4] 2> "nodeNames":["127.0.0.1:58226_solr"]}}
[junit4] 2> 342825 INFO (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest last action at 1519428048006000000 current time = 1519428053012000000
[junit4] 2> real diff: 5006000000
[junit4] 2> min diff: 4998000000
[junit4] 2> 342825 INFO (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest action executed from node_added_trigger1
[junit4] 2> 342827 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 5000ms
[junit4] 2> 342827 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger2 after 5000ms
[junit4] 2> 342827 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers Resuming trigger: node_added_trigger1 after 5000ms
[junit4] 2> 342827 DEBUG (AutoscalingActionExecutor-1504-thread-1-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.ScheduledTriggers -- processing took 1 ms for event id=a6e87f23804cfT732ukvjnyoqcuvvfgi0hwiz99
[junit4] 2> 342831 INFO (qtp24431827-3042) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 342834 INFO (qtp4875339-3113) [n:127.0.0.1:58211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 342840 INFO (qtp1650034-3135) [n:127.0.0.1:58226_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 342841 DEBUG (qtp24431827-3039) [n:127.0.0.1:58100_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 342842 INFO (qtp24431827-3039) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 342842 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 342843 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342844 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 342846 INFO (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 342847 INFO (qtp24431827-3039) [n:127.0.0.1:58100_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2
[junit4] 2> 342848 DEBUG (OverseerAutoScalingTriggerThread-72250025578397700-127.0.0.1:58100_solr-n_0000000001) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 342848 DEBUG (ScheduledTrigger-1503-thread-3) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: node_lost_trigger1 with currently live nodes: 3
[junit4] 2> 342851 INFO (qtp4875339-3114) [n:127.0.0.1:58211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 342853 INFO (qtp1650034-3141) [n:127.0.0.1:58226_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 342855 DEBUG (qtp4875339-3113) [n:127.0.0.1:58211_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 342855 INFO (qtp4875339-3113) [n:127.0.0.1:58211_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=12
[junit4] 2> 342855 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 342856 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342856 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger2 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 342856 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342857 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:58211_solr, 127.0.0.1:58100_solr, 127.0.0.1:58226_solr]
[junit4] 2> 342857 DEBUG (zkCallback-655-thread-2-processing-n:127.0.0.1:58100_solr) [n:127.0.0.1:58100_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger1 instantiated with properties: {event=nodeAdded, waitFor=0, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$ThrottlingTesterAction}], enabled=true}
[junit4] 2> 342857 DEBUG (zkCallback-655-thread-2-processin
[...truncated too long message...]
yticsQueryTest, SubstringBytesRefFilterTest, TestStressRecovery, OverseerStatusTest, TestTlogReplica, TestSimDistributedQueue, TestSolrCoreSnapshots, SuggestComponentTest, DeleteStatusTest, TestLargeCluster, TestXmlQParser, PeerSyncReplicationTest, TestReRankQParserPlugin, PeerSyncWithIndexFingerprintCachingTest, TestCollationFieldDocValues, ExitableDirectoryReaderTest, TestSerializedLuceneMatchVersion, LIRRollingUpdatesTest, TestOrdValues, TestFieldSortValues, TestStressUserVersions, JsonLoaderTest, BasicDistributedZk2Test, TestStressReorder, TestSolr4Spatial2, QueryParsingTest, TestFieldCache, SimpleFacetsTest, AutoAddReplicasPlanActionTest, TestWriterPerf, FullSolrCloudDistribCmdsTest, HdfsChaosMonkeyNothingIsSafeTest, ResponseLogComponentTest, TestSQLHandlerNonCloud, ClusterStateTest, TestUnifiedSolrHighlighter, TestPayloadScoreQParserPlugin, QueryResultKeyTest, BufferStoreTest, AddBlockUpdateTest, DistributedSpellCheckComponentTest, DeleteLastCustomShardedReplicaTest, CustomHighlightComponentTest, MoveReplicaHDFSFailoverTest, DirectUpdateHandlerTest, CachingDirectoryFactoryTest, TestDFRSimilarityFactory, CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, DistributedFacetPivotWhiteBoxTest, SystemInfoHandlerTest, ZkSolrClientTest, NodeMutatorTest, HttpPartitionOnCommitTest, TestScoreJoinQPScore, TestFieldCollectionResource, TestBlobHandler, XsltUpdateRequestHandlerTest, SpellPossibilityIteratorTest, CdcrUpdateLogTest, ScriptEngineTest, ShowFileRequestHandlerTest, TestSolrIndexConfig, TestSizeLimitedDistributedMap, TestCollapseQParserPlugin, HighlighterConfigTest, TestValueSourceCache, DistributedQueueTest, TestCrossCoreJoin, TestJsonFacets, BasicFunctionalityTest, HLLSerializationTest, SpatialHeatmapFacetsTest, TestRandomDVFaceting, TestBulkSchemaAPI]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaAPI -Dtests.seed=A32C490431C1F1C2 -Dtests.slow=true -Dtests.locale=es-PE -Dtests.timezone=Asia/Kathmandu -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TestBulkSchemaAPI (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.rest.schema.TestBulkSchemaAPI:
[junit4] > 1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=12013, name=qtp19872476-12013, state=TIMED_WAITING, group=TGRP-TestBulkSchemaAPI]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([A32C490431C1F1C2]:0)
[junit4] Completed [280/777 (3!)] on J1 in 98.59s, 10 tests, 2 errors <<< FAILURES!
[...truncated 3991 lines...]
[junit4] Suite: org.apache.solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\init-core-data-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1294, maxMBSortInHeap=7.981586594514307, sim=RandomSimilarity(queryNorm=true): {}, locale=el-CY, timezone=Asia/Urumqi
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_144 (32-bit)/cpus=3,threads=1,free=12950296,total=42663936
[junit4] 2> NOTE: All tests run in this JVM: [TestFoldingMultitermExtrasQuery, TestICUCollationFieldOptions, TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory -Dtests.seed=1C7690B804FAC724 -Dtests.slow=true -Dtests.locale=el-CY -Dtests.timezone=Asia/Urumqi -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J1 | TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory (suite) <<<
[junit4] > Throwable #1: java.io.IOException: Could not remove the following files (in the order of attempts):
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-tokenizer.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-ner-person.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001\tempDir-001\collection1\conf\en-test-sent.bin
[junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analysis-extras\test\J1\temp\solr.update.processor.TestOpenNLPExtractNamedEntitiesUpdateProcessorFactory_1C7690B804FAC724-001
[junit4] > at __randomizedtesting.SeedInfo.seed([1C7690B804FAC724]:0)
[junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [5/5 (1!)] on J1 in 1.15s, 8 tests, 1 error <<< FAILURES!
[...truncated 43379 lines...]