Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/494/ Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.lucene.index.TestIndexFileDeleter 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\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-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\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001 at __randomizedtesting.SeedInfo.seed([12B49D999A749E8F]: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.TestDistributedSearch.test Error Message: Error from server at http://127.0.0.1:63933//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:384) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:330) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:530) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382) 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: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:63933//collection1: java.lang.NullPointerException at org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:38) at org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:579) at org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:562) at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:195) at org.apache.solr.core.SolrCore.execute(SolrCore.java:2503) at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:711) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:517) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:384) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:330) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1637) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:533) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1595) at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1253) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:473) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1564) at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1155) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:527) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132) at org.eclipse.jetty.server.Server.handle(Server.java:530) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:347) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:256) at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279) at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:102) at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:247) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:140) at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:382) 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([B223A9FDCF640909:3A779627619864F1]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) at org.apache.solr.TestDistributedSearch.queryRandomUpServer(TestDistributedSearch.java:1168) at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1130) at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992) 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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) 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.core.TestInfoStreamLogging 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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001 C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001 at __randomizedtesting.SeedInfo.seed([B223A9FDCF640909]: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 644 lines...] [junit4] Suite: org.apache.lucene.index.TestIndexFileDeleter [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field=PostingsFormat(name=MockRandom), id=Lucene50(blocksize=128), content=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1479, maxMBSortInHeap=6.172957703815545, sim=RandomSimilarity(queryNorm=true): {field=DFR GL3(800.0), content=DFI(ChiSquared)}, locale=ar-SA, timezone=Africa/Mogadishu [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=48942040,total=196165632 [junit4] 2> NOTE: All tests run in this JVM: [TestPriorityQueue, TestTerm, TestPositiveScoresOnlyCollector, TestIndexableField, TestIndexWriterDeleteByQuery, TestTermQuery, TestFieldInfos, TestOneMergeWrappingMergePolicy, TestMixedCodecs, TestTieredMergePolicy, TestMSBRadixSorter, TestPointValues, TestLiveFieldValues, TestSpanNotQuery, TestOperations, TestIndexWriterUnicode, TestLockFactory, TestClassicSimilarity, TestNot, TestForUtil, TestTimSorterWorstCase, TestSpanBoostQuery, TestMergePolicyWrapper, TestRecyclingByteBlockAllocator, TestDocCount, TestIndexWriterFromReader, TestIndexWriter, MultiCollectorTest, TestBooleanRewrites, TestConjunctionDISI, TestExternalCodecs, TestBM25Similarity, TestLucene50TermVectorsFormat, TestSloppyPhraseQuery, TestReusableStringReader, TestFieldType, TestFieldValueQuery, TestDocInverterPerFieldErrorInfo, TestMatchAllDocsQuery, TestNeedsScores, TestSimpleAttributeImpl, TestNamedSPILoader, TestCollectionUtil, TestDuelingCodecs, TestSleepingLockWrapper, TestSimilarity2, TestIndexWriterOnVMError, TestTimeLimitingCollector, TestCrashCausesCorruptIndex, TestFixedLengthBytesRefArray, TestSpanSearchEquivalence, TestAllFilesDetectTruncation, TestCodecHoldsOpenFiles, TestDocIdSetBuilder, TestToken, TestBlockPostingsFormat2, TestMixedDocValuesUpdates, TestBagOfPositions, TestCodecUtil, TestFieldCacheRewriteMethod, TestDeterminizeLexicon, TestBooleanQueryVisitSubscorers, TestForceMergeForever, TestPointQueries, TestTermdocPerf, TestQueryBuilder, TestBinaryDocValuesUpdates, TestEarlyTerminatingSortingCollector, TestIndexFileDeleter] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestIndexFileDeleter -Dtests.seed=12B49D999A749E8F -Dtests.slow=true -Dtests.locale=ar-SA -Dtests.timezone=Africa/Mogadishu -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J0 | TestIndexFileDeleter (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\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001\tempDir-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.index.TestIndexFileDeleter_12B49D999A749E8F-001 [junit4] > at __randomizedtesting.SeedInfo.seed([12B49D999A749E8F]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [122/466 (1!)] on J0 in 0.63s, 10 tests, 1 error <<< FAILURES! [...truncated 12292 lines...] [junit4] Suite: org.apache.solr.TestDistributedSearch [junit4] 2> 907431 INFO (SUITE-TestDistributedSearch-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\init-core-data-001 [junit4] 2> 907433 WARN (SUITE-TestDistributedSearch-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 907433 INFO (SUITE-TestDistributedSearch-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 907439 INFO (SUITE-TestDistributedSearch-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061) [junit4] 2> 907439 INFO (SUITE-TestDistributedSearch-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 908015 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\control\cores\collection1 [junit4] 2> 908017 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 908017 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 908017 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 908017 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 908017 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@61004630{/,null,AVAILABLE} [junit4] 2> 908019 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@79741652{HTTP/1.1,[http/1.1]}{127.0.0.1:63874} [junit4] 2> 908019 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.Server Started @915918ms [junit4] 2> 908019 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=63874, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\control\cores} [junit4] 2> 908020 ERROR (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 908020 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 908020 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 908020 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 908020 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T00:36:58.617Z [junit4] 2> 908021 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\control\solr.xml [junit4] 2> 908029 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 908029 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 908029 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 908115 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908131 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908131 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908134 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\control\cores [junit4] 2> 908134 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 908134 INFO (coreLoadExecutor-4239-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 908135 INFO (coreLoadExecutor-4239-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> 908199 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 908214 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 908294 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 908304 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 908304 INFO (coreLoadExecutor-4239-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@2a2c6ec7 [junit4] 2> 908304 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 908304 INFO (coreLoadExecutor-4239-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.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\control\cores\collection1\data\] [junit4] 2> 908305 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=42.4482421875, floorSegmentMB=2.009765625, forceMergeDeletesPctAllowed=27.81615959962993, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49802117729558437 [junit4] 2> 908379 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 908380 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 908381 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 908381 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 908381 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=2.2470703125, floorSegmentMB=0.294921875, forceMergeDeletesPctAllowed=18.210629615879963, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4288842693947851 [junit4] 2> 908381 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3114e1df[collection1] main] [junit4] 2> 908382 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 908394 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 908460 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 908460 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 908460 INFO (coreLoadExecutor-4239-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594508935489912832 [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 908461 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 908462 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 908463 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 908464 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3114e1df[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 908895 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\shard0\cores\collection1 [junit4] 2> 908896 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T23:27:37+02:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8 [junit4] 2> 908897 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 908897 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 908897 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.session Scavenging every 660000ms [junit4] 2> 908897 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cba6f9a{/,null,AVAILABLE} [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@76983ea8{HTTP/1.1,[http/1.1]}{127.0.0.1:63877} [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.e.j.s.Server Started @916796ms [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=63877, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\shard0\cores} [junit4] 2> 908898 ERROR (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0 [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 908898 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-10T00:36:59.495Z [junit4] 2> 908899 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\shard0\solr.xml [junit4] 2> 908905 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 908905 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 908905 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 908972 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908979 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908979 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2a2c6ec7 [junit4] 2> 908982 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\shard0\cores [junit4] 2> 908982 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 908982 INFO (coreLoadExecutor-4250-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 908984 INFO (coreLoadExecutor-4250-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> 909048 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0 [junit4] 2> 909063 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 909153 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 909162 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1, trusted=true [junit4] 2> 909162 INFO (coreLoadExecutor-4250-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@2a2c6ec7 [junit4] 2> 909162 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 909162 INFO (coreLoadExecutor-4250-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.TestDistributedSearch_B223A9FDCF640909-001\tempDir-001\shard0\cores\collection1\data\] [junit4] 2> 909163 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=42, maxMergedSegmentMB=42.4482421875, floorSegmentMB=2.009765625, forceMergeDeletesPctAllowed=27.81615959962993, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.49802117729558437 [junit4] 2> 909245 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 909245 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 909246 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 909246 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 909246 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=2.2470703125, floorSegmentMB=0.294921875, forceMergeDeletesPctAllowed=18.210629615879963, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4288842693947851 [junit4] 2> 909247 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25a7f46c[collection1] main] [junit4] 2> 909247 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 909295 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 909351 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 909352 INFO (coreLoadExecutor-4250-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1594508936425242624 [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 909352 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 909353 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 909353 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 909354 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 909354 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25a7f46c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 909356 INFO (TEST-TestDistributedSearch.test-seed#[B223A9FDCF640909]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 909360 INFO (qtp473298246-11224) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594508936431534080)} 0 2 [junit4] 2> 909364 INFO (qtp1158295914-11234) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1594508936436776960)} 0 2 [junit4] 2> 909369 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1594508936438874112)]} 0 3 [junit4] 2> 909371 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1594508936444116992)]} 0 0 [junit4] 2> 909372 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1594508936445165568)]} 0 0 [junit4] 2> 909374 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1594508936446214144)]} 0 1 [junit4] 2> 909374 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1594508936448311296)]} 0 0 [junit4] 2> 909375 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1594508936449359872)]} 0 0 [junit4] 2> 909376 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1594508936449359872)]} 0 0 [junit4] 2> 909377 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1594508936450408448)]} 0 0 [junit4] 2> 909378 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1594508936451457024)]} 0 0 [junit4] 2> 909379 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1594508936453554176)]} 0 0 [junit4] 2> 909380 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1594508936454602752)]} 0 0 [junit4] 2> 909381 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1594508936455651328)]} 0 0 [junit4] 2> 909381 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1594508936455651328)]} 0 0 [junit4] 2> 909382 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1594508936456699904)]} 0 0 [junit4] 2> 909382 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1594508936456699904)]} 0 0 [junit4] 2> 909383 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1594508936457748480)]} 0 0 [junit4] 2> 909383 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1594508936457748480)]} 0 0 [junit4] 2> 909383 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1594508936457748481)]} 0 0 [junit4] 2> 909384 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594508936458797056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 909384 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@797dd7e1 commitCommandVersion:1594508936458797056 [junit4] 2> 909391 INFO (qtp473298246-11221) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@14f80280[collection1] main] [junit4] 2> 909392 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 909393 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@14f80280[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)))} [junit4] 2> 909393 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9 [junit4] 2> 909393 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594508936468234240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 909393 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ab8dd8f commitCommandVersion:1594508936468234240 [junit4] 2> 909400 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2078ddb[collection1] main] [junit4] 2> 909402 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2078ddb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)))} [junit4] 2> 909402 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 909402 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8 [junit4] 2> 909402 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1594508936477671424)]} 0 1 [junit4] 2> 909407 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1594508936477671424)]} 0 2 [junit4] 2> 909408 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1594508936482914304)]} 0 0 [junit4] 2> 909409 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1594508936485011456)]} 0 0 [junit4] 2> 909410 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1594508936486060032)]} 0 0 [junit4] 2> 909411 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1594508936487108608)]} 0 0 [junit4] 2> 909411 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1594508936487108608)]} 0 0 [junit4] 2> 909411 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1594508936487108609)]} 0 0 [junit4] 2> 909412 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1594508936488157184)]} 0 0 [junit4] 2> 909413 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1594508936489205760)]} 0 0 [junit4] 2> 909413 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1594508936489205760)]} 0 0 [junit4] 2> 909414 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1594508936490254336)]} 0 0 [junit4] 2> 909414 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1594508936490254336)]} 0 0 [junit4] 2> 909415 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1594508936491302912)]} 0 0 [junit4] 2> 909415 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1594508936491302912)]} 0 0 [junit4] 2> 909416 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1594508936492351488)]} 0 0 [junit4] 2> 909416 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1594508936492351488)]} 0 0 [junit4] 2> 909416 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1594508936492351489)]} 0 0 [junit4] 2> 909416 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1594508936492351489)]} 0 0 [junit4] 2> 909417 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1594508936493400064)]} 0 0 [junit4] 2> 909417 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1594508936493400064)]} 0 0 [junit4] 2> 909417 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1594508936493400065)]} 0 0 [junit4] 2> 909418 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1594508936494448640)]} 0 0 [junit4] 2> 909418 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1594508936494448640)]} 0 0 [junit4] 2> 909419 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1594508936495497216)]} 0 0 [junit4] 2> 909419 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1594508936495497216)]} 0 0 [junit4] 2> 909420 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1594508936495497217)]} 0 0 [junit4] 2> 909420 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1594508936496545792)]} 0 0 [junit4] 2> 909420 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1594508936496545792)]} 0 0 [junit4] 2> 909421 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1594508936497594368)]} 0 0 [junit4] 2> 909421 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1594508936497594368)]} 0 0 [junit4] 2> 909421 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1594508936497594369)]} 0 0 [junit4] 2> 909422 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1594508936498642944)]} 0 0 [junit4] 2> 909422 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1594508936498642944)]} 0 0 [junit4] 2> 909422 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1594508936498642945)]} 0 0 [junit4] 2> 909423 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1594508936499691520)]} 0 0 [junit4] 2> 909423 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1594508936499691520)]} 0 0 [junit4] 2> 909423 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1594508936499691521)]} 0 0 [junit4] 2> 909424 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1594508936500740096)]} 0 0 [junit4] 2> 909424 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1594508936500740096)]} 0 0 [junit4] 2> 909424 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1594508936500740097)]} 0 0 [junit4] 2> 909425 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1594508936500740097)]} 0 0 [junit4] 2> 909425 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1594508936501788672)]} 0 0 [junit4] 2> 909425 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1594508936501788672)]} 0 0 [junit4] 2> 909426 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1594508936501788673)]} 0 0 [junit4] 2> 909426 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1594508936502837248)]} 0 0 [junit4] 2> 909426 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1594508936502837248)]} 0 0 [junit4] 2> 909427 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1594508936502837249)]} 0 0 [junit4] 2> 909427 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1594508936503885824)]} 0 0 [junit4] 2> 909427 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1594508936503885824)]} 0 0 [junit4] 2> 909427 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1594508936503885825)]} 0 0 [junit4] 2> 909428 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1594508936504934400)]} 0 0 [junit4] 2> 909428 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1594508936504934400)]} 0 0 [junit4] 2> 909428 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1594508936504934401)]} 0 0 [junit4] 2> 909429 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1594508936505982976)]} 0 0 [junit4] 2> 909429 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1594508936505982976)]} 0 0 [junit4] 2> 909429 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1594508936505982977)]} 0 0 [junit4] 2> 909430 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1594508936507031552)]} 0 0 [junit4] 2> 909430 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1594508936507031552)]} 0 0 [junit4] 2> 909430 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1594508936507031553)]} 0 0 [junit4] 2> 909431 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1594508936507031553)]} 0 0 [junit4] 2> 909431 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1594508936508080128)]} 0 0 [junit4] 2> 909431 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1594508936508080128)]} 0 0 [junit4] 2> 909432 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1594508936508080129)]} 0 0 [junit4] 2> 909432 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1594508936509128704)]} 0 0 [junit4] 2> 909432 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1594508936509128704)]} 0 0 [junit4] 2> 909433 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1594508936509128705)]} 0 0 [junit4] 2> 909433 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1594508936510177280)]} 0 0 [junit4] 2> 909433 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1594508936510177280)]} 0 0 [junit4] 2> 909434 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1594508936511225856)]} 0 0 [junit4] 2> 909434 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1594508936511225856)]} 0 0 [junit4] 2> 909434 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1594508936511225857)]} 0 0 [junit4] 2> 909435 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1594508936511225857)]} 0 0 [junit4] 2> 909435 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1594508936512274432)]} 0 0 [junit4] 2> 909435 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1594508936512274432)]} 0 0 [junit4] 2> 909435 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1594508936512274433)]} 0 0 [junit4] 2> 909436 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1594508936513323008)]} 0 0 [junit4] 2> 909436 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1594508936513323008)]} 0 0 [junit4] 2> 909436 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1594508936513323009)]} 0 0 [junit4] 2> 909437 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1594508936514371584)]} 0 0 [junit4] 2> 909437 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1594508936514371584)]} 0 0 [junit4] 2> 909437 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1594508936514371585)]} 0 0 [junit4] 2> 909438 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1594508936515420160)]} 0 0 [junit4] 2> 909438 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1594508936515420160)]} 0 0 [junit4] 2> 909438 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1594508936515420161)]} 0 0 [junit4] 2> 909438 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1594508936515420161)]} 0 0 [junit4] 2> 909438 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1594508936515420162)]} 0 0 [junit4] 2> 909438 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1594508936515420162)]} 0 0 [junit4] 2> 909438 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1594508936515420163)]} 0 0 [junit4] 2> 909438 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1594508936515420163)]} 0 0 [junit4] 2> 909441 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1594508936518565888)]} 0 0 [junit4] 2> 909441 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1594508936518565888)]} 0 0 [junit4] 2> 909441 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1594508936518565889)]} 0 0 [junit4] 2> 909441 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1594508936518565889)]} 0 0 [junit4] 2> 909441 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1594508936518565890)]} 0 0 [junit4] 2> 909442 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1594508936519614464)]} 0 0 [junit4] 2> 909442 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1594508936519614464)]} 0 0 [junit4] 2> 909442 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1594508936519614465)]} 0 0 [junit4] 2> 909443 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1594508936519614465)]} 0 0 [junit4] 2> 909443 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1594508936520663040)]} 0 0 [junit4] 2> 909443 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1594508936520663040)]} 0 0 [junit4] 2> 909444 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1594508936521711616)]} 0 0 [junit4] 2> 909445 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1594508936522760192)]} 0 0 [junit4] 2> 909445 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1594508936522760192)]} 0 0 [junit4] 2> 909446 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1594508936523808768)]} 0 0 [junit4] 2> 909446 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1594508936523808768)]} 0 0 [junit4] 2> 909447 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1594508936524857344)]} 0 0 [junit4] 2> 909447 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1594508936524857344)]} 0 0 [junit4] 2> 909447 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1594508936524857345)]} 0 0 [junit4] 2> 909448 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1594508936525905920)]} 0 0 [junit4] 2> 909448 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1594508936525905920)]} 0 0 [junit4] 2> 909448 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1594508936525905921)]} 0 0 [junit4] 2> 909449 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1594508936525905921)]} 0 0 [junit4] 2> 909449 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1594508936526954496)]} 0 0 [junit4] 2> 909449 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1594508936526954496)]} 0 0 [junit4] 2> 909449 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1594508936526954497)]} 0 0 [junit4] 2> 909450 INFO (qtp473298246-11219) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1594508936528003072)]} 0 0 [junit4] 2> 909450 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1594508936528003072)]} 0 0 [junit4] 2> 909450 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594508936528003073,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 909450 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@797dd7e1 commitCommandVersion:1594508936528003073 [junit4] 2> 909455 INFO (qtp473298246-11221) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5eb2d7f4[collection1] main] [junit4] 2> 909456 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 909457 INFO (searcherExecutor-4240-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5eb2d7f4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9) Uninverting(_1(7.3.0):c59)))} [junit4] 2> 909457 INFO (qtp473298246-11221) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7 [junit4] 2> 909458 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1594508936536391680,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 909458 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5ab8dd8f commitCommandVersion:1594508936536391680 [junit4] 2> 909462 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11e75310[collection1] main] [junit4] 2> 909462 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush [junit4] 2> 909466 INFO (searcherExecutor-4251-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11e75310[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9) Uninverting(_1(7.3.0):c59)))} [junit4] 2> 909466 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8 [junit4] 2> 909467 INFO (qtp473298246-11219) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 909467 INFO (qtp473298246-11219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 909467 INFO (qtp473298246-11221) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative [junit4] 2> 909467 INFO (qtp473298246-11221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0 [junit4] 2> 909468 INFO (qtp473298246-11219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909472 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:63877//collection1|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1520642220066&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909473 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220066&ids=143,133,134,125,104,17,117,9,140,131&isShard=true&shard.url=http://127.0.0.1:63877//collection1|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909493 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:63877//collection1|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=23 [junit4] 2> 909494 INFO (qtp473298246-11221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909498 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1520642220093&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909499 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220093&ids=110,1,100,111,145,13,113,115,137,109&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909499 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4 [junit4] 2> 909501 INFO (qtp473298246-11219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909524 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:63877//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1520642220099&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909526 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220099&ids=121,12,102,114,126,137,148,115,138,142&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:63877//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909526 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|http://127.0.0.1:63877//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=24 [junit4] 2> 909527 INFO (qtp473298246-11221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909529 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:63877//collection1&rows=10&version=2&q=*:*&NOW=1520642220125&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909530 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220125&ids=143,133,3,135,15,5,139,118,141,131&isShard=true&shard.url=http://127.0.0.1:63877//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909530 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:63877//collection1&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 909531 INFO (qtp473298246-11219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909532 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:63877//collection1&rows=10&version=2&q=*:*&NOW=1520642220129&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909533 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220129&ids=133,122,12,111,14,15,137,138,7,129&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:63877//collection1&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909534 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:63877//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 909535 INFO (qtp473298246-11221) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909536 INFO (qtp1158295914-11238) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1520642220132&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909537 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220132&ids=143,135,3,113,148,5,104,107,119,130&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 909538 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:63877//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=2 [junit4] 2> 909539 INFO (qtp473298246-11219) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0 [junit4] 2> 909540 INFO (qtp1158295914-11239) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:63877//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1520642220136&isShard=true&wt=javabin} hits=68 status=0 QTime=0 [junit4] 2> 909541 INFO (qtp1158295914-11236) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1520642220136&ids=111,1,145,112,113,4,17,117,128,10&isShard=true&shard.url=http://127.0.0.1:63877//collection1|[ff01: [...truncated too long message...] 2> 1202985 INFO (coreCloseExecutor-5704-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-5704-thread-1]: now checkpoint "" [0 segments ; isCommit = false] [junit4] 2> 1202985 INFO (coreCloseExecutor-5704-thread-1) [ x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-5704-thread-1]: 0 msec to checkpoint [junit4] 2> 1202986 INFO (SUITE-TestInfoStreamLogging-seed#[B223A9FDCF640909]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1050, maxMBSortInHeap=7.4255288971573705, sim=RandomSimilarity(queryNorm=false): {}, locale=de-DE, timezone=MST [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 (64-bit)/cpus=3,threads=1,free=146118920,total=448176128 [junit4] 2> NOTE: All tests run in this JVM: [CSVRequestHandlerTest, DistributedQueryComponentCustomSortTest, FieldAnalysisRequestHandlerTest, CreateRoutedAliasTest, HLLUtilTest, TestSolrConfigHandlerCloud, TestSegmentSorting, TestSolr4Spatial, BadIndexSchemaTest, TestSolrCloudWithHadoopAuthPlugin, TestInPlaceUpdatesDistrib, TestSearchPerf, CollectionsAPIAsyncDistributedZkTest, BadCopyFieldTest, CurrencyRangeFacetCloudTest, TestLMDirichletSimilarityFactory, TestNodeAddedTrigger, TestLMJelinekMercerSimilarityFactory, DistributedSuggestComponentTest, FullHLLTest, ConjunctionSolrSpellCheckerTest, RulesTest, TestCoreDiscovery, TestHashPartitioner, NoCacheHeaderTest, TestFileDictionaryLookup, TestRequestStatusCollectionAPI, TestCloudSchemaless, LeaderFailureAfterFreshStartTest, OverseerTaskQueueTest, TestStressVersions, DOMUtilTest, SuggesterTest, CoreAdminHandlerTest, ReplicationFactorTest, NumberUtilsTest, HighlighterConfigTest, TestDynamicLoading, TestLeaderElectionWithEmptyReplica, TestLRUStatsCache, TestJavabinTupleStreamParser, TestAnalyzeInfixSuggestions, DefaultValueUpdateProcessorTest, TestMacros, TestDistributedGrouping, TestHalfAndHalfDocValues, ScriptEngineTest, BitVectorTest, TestComponentsName, JsonLoaderTest, TestCloudConsistency, UpdateLogTest, PreAnalyzedFieldManagedSchemaCloudTest, TestPrepRecovery, URLClassifyProcessorTest, TestRequestForwarding, AtomicUpdatesTest, SoftAutoCommitTest, TestRandomFaceting, TestTrieFacet, SpellCheckComponentTest, OverseerTest, RequestLoggingTest, LoggingHandlerTest, TestSolrQueryParser, TestSizeLimitedDistributedMap, BlockDirectoryTest, TestNumericTerms32, DistributedFacetPivotSmallTest, TestLuceneMatchVersion, PreAnalyzedFieldTest, SimpleCollectionCreateDeleteTest, TestNumericRangeQuery64, ComputePlanActionTest, RollingRestartTest, SolrCLIZkUtilsTest, BlockJoinFacetDistribTest, DirectoryFactoryTest, MetricUtilsTest, TestChildDocTransformer, CircularListTest, TestConfigSetsAPIExclusivity, SimpleMLTQParserTest, FileBasedSpellCheckerTest, DirectUpdateHandlerTest, TestEmbeddedSolrServerSchemaAPI, RegexBoostProcessorTest, TestShardHandlerFactory, TestNoOpRegenerator, TimeZoneUtilsTest, TestMiniSolrCloudClusterSSL, QueryParsingTest, AddSchemaFieldsUpdateProcessorFactoryTest, DateRangeFieldTest, SimpleFacetsTest, TestHttpShardHandlerFactory, TestRawResponseWriter, TestCollectionsAPIViaSolrCloudCluster, DebugComponentTest, SmileWriterTest, ClusterStateTest, TestCloudNestedDocsSort, SyncSliceTest, HdfsRecoverLeaseTest, TestDistributedStatsComponentCardinality, SolrCmdDistributorTest, SaslZkACLProviderTest, TestDFISimilarityFactory, TestDistributedSearch, TestDistribDocBasedVersion, UUIDFieldTest, TestMinMaxOnMultiValuedField, HdfsRestartWhileUpdatingTest, ZkSolrClientTest, OverseerCollectionConfigSetProcessorTest, AutoAddReplicasPlanActionTest, TestSimpleTrackingShardHandler, TestQuerySenderListener, TestSolrCoreProperties, TestSortByMinMaxFunction, ResponseLogComponentTest, TestSolrDeletionPolicy2, OverseerRolesTest, TestRetrieveFieldsOptimizer, HdfsThreadLeakTest, TestPolicyCloud, DeleteReplicaTest, TestDocumentBuilder, TestRTGBase, UUIDUpdateProcessorFallbackTest, TestBackupRepositoryFactory, ShardRoutingTest, SearchHandlerTest, TestSolrConfigHandler, DistributedFacetPivotLongTailTest, VMParamsZkACLAndCredentialsProvidersTest, BasicDistributedZk2Test, HdfsChaosMonkeySafeLeaderTest, ExternalFileFieldSortTest, TestSolrCLIRunExample, TestAtomicUpdateErrorCases, TestExtendedDismaxParser, TestSolrCloudWithDelegationTokens, BlockJoinFacetSimpleTest, TestComplexPhraseLeadingWildcard, HdfsBasicDistributedZkTest, TestPHPSerializedResponseWriter, CdcrRequestHandlerTest, TestInfoStreamLogging] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestInfoStreamLogging -Dtests.seed=B223A9FDCF640909 -Dtests.slow=true -Dtests.locale=de-DE -Dtests.timezone=MST -Dtests.asserts=true -Dtests.file.encoding=Cp1252 [junit4] ERROR 0.00s J1 | TestInfoStreamLogging (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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-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.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001 [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock: java.nio.file.AccessDeniedException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001\init-core-data-001\index\write.lock [junit4] > C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001: java.nio.file.DirectoryNotEmptyException: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.core.TestInfoStreamLogging_B223A9FDCF640909-001 [junit4] > at __randomizedtesting.SeedInfo.seed([B223A9FDCF640909]:0) [junit4] > at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329) [junit4] > at java.lang.Thread.run(Thread.java:748) [junit4] Completed [295/782 (2!)] on J1 in 0.60s, 1 test, 1 error <<< FAILURES! [...truncated 47685 lines...] -ecj-javadoc-lint-tests: [mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj1596065088 [ecj-lint] Compiling 874 source files to C:\Users\jenkins\AppData\Local\Temp\ecj1596065088 [ecj-lint] invalid Class-Path header in manifest of jar file: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\lib\org.restlet-2.3.0.jar [ecj-lint] invalid Class-Path header in manifest of jar file: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\lib\org.restlet.ext.servlet-2.3.0.jar [ecj-lint] ---------- [ecj-lint] 1. WARNING in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test\org\apache\solr\analysis\TokenizerChainTest.java (at line 37) [ecj-lint] TokenizerChain tokenizerChain = new TokenizerChain( [ecj-lint] ^^^^^^^^^^^^^^ [ecj-lint] Resource leak: 'tokenizerChain' is never closed [ecj-lint] ---------- [ecj-lint] ---------- [ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test\org\apache\solr\cloud\TestCloudConsistency.java (at line 23) [ecj-lint] import java.net.URL; [ecj-lint] ^^^^^^^^^^^^ [ecj-lint] The import java.net.URL is never used [ecj-lint] ---------- [ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test\org\apache\solr\cloud\TestCloudConsistency.java (at line 39) [ecj-lint] import org.apache.solr.common.cloud.ZkStateReader; [ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [ecj-lint] The import org.apache.solr.common.cloud.ZkStateReader is never used [ecj-lint] ---------- [ecj-lint] 3 problems (2 errors, 1 warning) BUILD FAILED C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:618: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\build.xml:101: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build.xml:682: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:2088: The following error occurred while executing this line: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\common-build.xml:2121: Compile failed; see the compiler error output for details. Total time: 87 minutes 55 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 [WARNINGS] Skipping publisher since build result is FAILURE Recording test results Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Email was triggered for: Failure - Any Sending email for trigger: Failure - Any Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2 Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org